2025-10-15 14:56:46,530 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 14:56:46,530 - INFO - ya.test - pytest_runtest_setup: test_case
2025-10-15 14:56:46,530 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 14:56:46,530 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 14:56:46,574 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd
2025-10-15 14:56:46,574 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24848
2025-10-15 14:56:46,575 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/pv2i/0030ef/r3tmp/kikimr_cluster_yy15bzqs
2025-10-15 14:56:46,598 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd
2025-10-15 14:56:46,599 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30916
2025-10-15 14:56:46,599 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6160
2025-10-15 14:56:46,599 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15829
2025-10-15 14:56:46,600 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25806
2025-10-15 14:56:46,600 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/kikimr_configs"
2025-10-15 14:56:46,600 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/pv2i/0030ef/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/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/node_1/logfile_z7zkooe7.log --grpc-port=30916 --mon-port=6160 --ic-port=24848
2025-10-15 14:56:46,601 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:30916/1, disk_size 68719476736
2025-10-15 14:56:46,601 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/kikimr_configs"
2025-10-15 14:56:46,601 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/pv2i/0030ef/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/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/node_1/logfile_z7zkooe7.log --grpc-port=30916 --mon-port=6160 --ic-port=24848
2025-10-15 14:56:46,601 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/node_1/logfile_z7zkooe7.log', '--grpc-port=30916', '--mon-port=6160', '--ic-port=24848')' in '/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/node_1' (no wait)
2025-10-15 14:56:46,603 - DEBUG - ya.test - execute: Command pid: 728249
2025-10-15 14:56:46,603 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:30916/1
2025-10-15 14:56:46,604 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-15 14:56:46,604 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-15 14:56:46,604 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 14:56:46,605 - DEBUG - ya.test - execute: Command pid: 728250
2025-10-15 14:56:47,186 - DEBUG - ya.test - _save_outputs: Command (pid 728250) rc: 0
2025-10-15 14:56:47,187 - DEBUG - ya.test - _save_outputs: Command (pid 728250) elapsed time (sec): 0.5810818672180176
2025-10-15 14:56:47,187 - DEBUG - ya.test - _save_outputs: Command (pid 728250) idrss: 0
2025-10-15 14:56:47,187 - DEBUG - ya.test - _save_outputs: Command (pid 728250) inblock: 0
2025-10-15 14:56:47,187 - DEBUG - ya.test - _save_outputs: Command (pid 728250) isrss: 0
2025-10-15 14:56:47,187 - DEBUG - ya.test - _save_outputs: Command (pid 728250) ixrss: 0
2025-10-15 14:56:47,188 - DEBUG - ya.test - _save_outputs: Command (pid 728250) majflt: 1
2025-10-15 14:56:47,188 - DEBUG - ya.test - _save_outputs: Command (pid 728250) maxrss: 537820
2025-10-15 14:56:47,188 - DEBUG - ya.test - _save_outputs: Command (pid 728250) minflt: 16595
2025-10-15 14:56:47,188 - DEBUG - ya.test - _save_outputs: Command (pid 728250) msgrcv: 0
2025-10-15 14:56:47,188 - DEBUG - ya.test - _save_outputs: Command (pid 728250) msgsnd: 0
2025-10-15 14:56:47,188 - DEBUG - ya.test - _save_outputs: Command (pid 728250) nivcsw: 5
2025-10-15 14:56:47,188 - DEBUG - ya.test - _save_outputs: Command (pid 728250) nsignals: 0
2025-10-15 14:56:47,189 - DEBUG - ya.test - _save_outputs: Command (pid 728250) nswap: 0
2025-10-15 14:56:47,189 - DEBUG - ya.test - _save_outputs: Command (pid 728250) nvcsw: 14
2025-10-15 14:56:47,189 - DEBUG - ya.test - _save_outputs: Command (pid 728250) oublock: 8
2025-10-15 14:56:47,189 - DEBUG - ya.test - _save_outputs: Command (pid 728250) stime: 0.41544699999999996
2025-10-15 14:56:47,189 - DEBUG - ya.test - _save_outputs: Command (pid 728250) utime: 0.117431
2025-10-15 14:56:47,189 - DEBUG - ya.test - _save_outputs: Command (pid 728250) wtime: 0.582
2025-10-15 14:56:47,190 - DEBUG - ya.test - _save_outputs: Command (pid 728250) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

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

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

2025-10-15 14:56:47,190 - DEBUG - ya.test - _save_outputs: Command (pid 728250) errors:

2025-10-15 14:56:47,190 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b
2025-10-15 14:56:49,005 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd
2025-10-15 14:56:49,005 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:30916', '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: 24848\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-15 14:56:49,006 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-15 14:56:49,007 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-15 14:56:49,007 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:30916', '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: 24848\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 14:56:49,008 - DEBUG - ya.test - execute: Command pid: 729623
2025-10-15 14:56:49,550 - DEBUG - ya.test - _save_outputs: Command (pid 729623) rc: 0
2025-10-15 14:56:49,550 - DEBUG - ya.test - _save_outputs: Command (pid 729623) elapsed time (sec): 0.541400671005249
2025-10-15 14:56:49,551 - DEBUG - ya.test - _save_outputs: Command (pid 729623) idrss: 0
2025-10-15 14:56:49,551 - DEBUG - ya.test - _save_outputs: Command (pid 729623) inblock: 0
2025-10-15 14:56:49,551 - DEBUG - ya.test - _save_outputs: Command (pid 729623) isrss: 0
2025-10-15 14:56:49,551 - DEBUG - ya.test - _save_outputs: Command (pid 729623) ixrss: 0
2025-10-15 14:56:49,551 - DEBUG - ya.test - _save_outputs: Command (pid 729623) majflt: 0
2025-10-15 14:56:49,551 - DEBUG - ya.test - _save_outputs: Command (pid 729623) maxrss: 546232
2025-10-15 14:56:49,551 - DEBUG - ya.test - _save_outputs: Command (pid 729623) minflt: 18232
2025-10-15 14:56:49,551 - DEBUG - ya.test - _save_outputs: Command (pid 729623) msgrcv: 0
2025-10-15 14:56:49,551 - DEBUG - ya.test - _save_outputs: Command (pid 729623) msgsnd: 0
2025-10-15 14:56:49,552 - DEBUG - ya.test - _save_outputs: Command (pid 729623) nivcsw: 1
2025-10-15 14:56:49,552 - DEBUG - ya.test - _save_outputs: Command (pid 729623) nsignals: 0
2025-10-15 14:56:49,552 - DEBUG - ya.test - _save_outputs: Command (pid 729623) nswap: 0
2025-10-15 14:56:49,552 - DEBUG - ya.test - _save_outputs: Command (pid 729623) nvcsw: 145
2025-10-15 14:56:49,552 - DEBUG - ya.test - _save_outputs: Command (pid 729623) oublock: 8
2025-10-15 14:56:49,552 - DEBUG - ya.test - _save_outputs: Command (pid 729623) stime: 0.391536
2025-10-15 14:56:49,552 - DEBUG - ya.test - _save_outputs: Command (pid 729623) utime: 0.107874
2025-10-15 14:56:49,552 - DEBUG - ya.test - _save_outputs: Command (pid 729623) wtime: 0.542
2025-10-15 14:56:49,553 - DEBUG - ya.test - _save_outputs: Command (pid 729623) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-15 14:56:49,553 - DEBUG - ya.test - _save_outputs: Command (pid 729623) errors:

2025-10-15 14:56:49,554 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd
2025-10-15 14:56:49,554 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:30916', '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-15 14:56:49,555 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-15 14:56:49,556 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-15 14:56:49,556 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:30916', '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-15 14:56:49,557 - DEBUG - ya.test - execute: Command pid: 729760
2025-10-15 14:56:50,260 - DEBUG - ya.test - _save_outputs: Command (pid 729760) rc: 0
2025-10-15 14:56:50,261 - DEBUG - ya.test - _save_outputs: Command (pid 729760) elapsed time (sec): 0.7029173374176025
2025-10-15 14:56:50,261 - DEBUG - ya.test - _save_outputs: Command (pid 729760) idrss: 0
2025-10-15 14:56:50,261 - DEBUG - ya.test - _save_outputs: Command (pid 729760) inblock: 0
2025-10-15 14:56:50,261 - DEBUG - ya.test - _save_outputs: Command (pid 729760) isrss: 0
2025-10-15 14:56:50,261 - DEBUG - ya.test - _save_outputs: Command (pid 729760) ixrss: 0
2025-10-15 14:56:50,261 - DEBUG - ya.test - _save_outputs: Command (pid 729760) majflt: 0
2025-10-15 14:56:50,262 - DEBUG - ya.test - _save_outputs: Command (pid 729760) maxrss: 545976
2025-10-15 14:56:50,262 - DEBUG - ya.test - _save_outputs: Command (pid 729760) minflt: 18230
2025-10-15 14:56:50,262 - DEBUG - ya.test - _save_outputs: Command (pid 729760) msgrcv: 0
2025-10-15 14:56:50,262 - DEBUG - ya.test - _save_outputs: Command (pid 729760) msgsnd: 0
2025-10-15 14:56:50,262 - DEBUG - ya.test - _save_outputs: Command (pid 729760) nivcsw: 4
2025-10-15 14:56:50,263 - DEBUG - ya.test - _save_outputs: Command (pid 729760) nsignals: 0
2025-10-15 14:56:50,263 - DEBUG - ya.test - _save_outputs: Command (pid 729760) nswap: 0
2025-10-15 14:56:50,263 - DEBUG - ya.test - _save_outputs: Command (pid 729760) nvcsw: 142
2025-10-15 14:56:50,263 - DEBUG - ya.test - _save_outputs: Command (pid 729760) oublock: 8
2025-10-15 14:56:50,263 - DEBUG - ya.test - _save_outputs: Command (pid 729760) stime: 0.545555
2025-10-15 14:56:50,263 - DEBUG - ya.test - _save_outputs: Command (pid 729760) utime: 0.113495
2025-10-15 14:56:50,263 - DEBUG - ya.test - _save_outputs: Command (pid 729760) wtime: 0.704
2025-10-15 14:56:50,264 - DEBUG - ya.test - _save_outputs: Command (pid 729760) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-15 14:56:50,264 - DEBUG - ya.test - _save_outputs: Command (pid 729760) errors:

2025-10-15 14:56:50,264 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-15 14:56:50,278 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-15 14:56:50,289 - DEBUG - __tests__.test_system_views - setup_method: Create database /Root/users/TestQueryMetricsUniqueQueries_test_case
2025-10-15 14:56:50,798 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd
2025-10-15 14:56:50,799 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16824
2025-10-15 14:56:50,799 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16486
2025-10-15 14:56:50,800 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1138
2025-10-15 14:56:50,800 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63911
2025-10-15 14:56:50,800 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21839
2025-10-15 14:56:50,801 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/kikimr_configs"
2025-10-15 14:56:50,801 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:30916 --tenant=/Root/users/TestQueryMetricsUniqueQueries_test_case --log-file-name=/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/slot_1/logfile_xwtf182e.log --grpc-port=16824 --mon-port=16486 --ic-port=1138
2025-10-15 14:56:50,802 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/kikimr_configs"
2025-10-15 14:56:50,802 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:30916 --tenant=/Root/users/TestQueryMetricsUniqueQueries_test_case --log-file-name=/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/slot_1/logfile_xwtf182e.log --grpc-port=16824 --mon-port=16486 --ic-port=1138
2025-10-15 14:56:50,803 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:30916', '--tenant=/Root/users/TestQueryMetricsUniqueQueries_test_case', '--log-file-name=/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/slot_1/logfile_xwtf182e.log', '--grpc-port=16824', '--mon-port=16486', '--ic-port=1138')' in '/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/test_system_views.py.TestQueryMetricsUniqueQueries.test_case/cluster/slot_1' (no wait)
2025-10-15 14:56:50,804 - DEBUG - ya.test - execute: Command pid: 730970
2025-10-15 14:56:50,805 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:16824/1
2025-10-15 14:56:50,806 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-15 14:56:50,806 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-15 14:56:50,806 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pv2i/0030ef/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 14:56:50,807 - DEBUG - ya.test - execute: Command pid: 730976
2025-10-15 14:56:51,328 - DEBUG - ya.test - _save_outputs: Command (pid 730976) rc: 0
2025-10-15 14:56:51,329 - DEBUG - ya.test - _save_outputs: Command (pid 730976) elapsed time (sec): 0.5204222202301025
2025-10-15 14:56:51,329 - DEBUG - ya.test - _save_outputs: Command (pid 730976) idrss: 0
2025-10-15 14:56:51,329 - DEBUG - ya.test - _save_outputs: Command (pid 730976) inblock: 0
2025-10-15 14:56:51,330 - DEBUG - ya.test - _save_outputs: Command (pid 730976) isrss: 0
2025-10-15 14:56:51,330 - DEBUG - ya.test - _save_outputs: Command (pid 730976) ixrss: 0
2025-10-15 14:56:51,330 - DEBUG - ya.test - _save_outputs: Command (pid 730976) majflt: 0
2025-10-15 14:56:51,330 - DEBUG - ya.test - _save_outputs: Command (pid 730976) maxrss: 537820
2025-10-15 14:56:51,330 - DEBUG - ya.test - _save_outputs: Command (pid 730976) minflt: 21010
2025-10-15 14:56:51,330 - DEBUG - ya.test - _save_outputs: Command (pid 730976) msgrcv: 0
2025-10-15 14:56:51,331 - DEBUG - ya.test - _save_outputs: Command (pid 730976) msgsnd: 0
2025-10-15 14:56:51,331 - DEBUG - ya.test - _save_outputs: Command (pid 730976) nivcsw: 5
2025-10-15 14:56:51,331 - DEBUG - ya.test - _save_outputs: Command (pid 730976) nsignals: 0
2025-10-15 14:56:51,331 - DEBUG - ya.test - _save_outputs: Command (pid 730976) nswap: 0
2025-10-15 14:56:51,331 - DEBUG - ya.test - _save_outputs: Command (pid 730976) nvcsw: 17
2025-10-15 14:56:51,332 - DEBUG - ya.test - _save_outputs: Command (pid 730976) oublock: 8
2025-10-15 14:56:51,332 - DEBUG - ya.test - _save_outputs: Command (pid 730976) stime: 0.352344
2025-10-15 14:56:51,332 - DEBUG - ya.test - _save_outputs: Command (pid 730976) utime: 0.11877099999999999
2025-10-15 14:56:51,332 - DEBUG - ya.test - _save_outputs: Command (pid 730976) wtime: 0.521
2025-10-15 14:56:51,332 - DEBUG - ya.test - _save_outputs: Command (pid 730976) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

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

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

2025-10-15 14:56:51,333 - DEBUG - ya.test - _save_outputs: Command (pid 730976) errors:

2025-10-15 14:56:51,333 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b
2025-10-15 14:56:53,341 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_system_views.py::TestQueryMetricsUniqueQueries, test_name: test_case)
2025-10-15 14:56:53,341 - 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-15 14:56:53,343 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 14:56:53,345 - 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-15 14:56:53,352 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/TestQueryMetricsUniqueQueries_test_case
2025-10-15 14:56:53,353 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, de513cfa-bf91-43a9-b615-5d4b13c61dd8, localhost:30916): creating call state
2025-10-15 14:56:53,353 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, de513cfa-bf91-43a9-b615-5d4b13c61dd8, localhost:30916): request = { database: "/Root/users/TestQueryMetricsUniqueQueries_test_case" }
2025-10-15 14:56:53,355 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, de513cfa-bf91-43a9-b615-5d4b13c61dd8, localhost:30916): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-w3odipbbdi.auto.internal" port: 16824 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-15 14:56:53,356 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/TestQueryMetricsUniqueQueries_test_case: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-w3odipbbdi.auto.internal:16824, location , ssl: False>]>
2025-10-15 14:56:53,356 - 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-15 14:56:53,364 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:30916
2025-10-15 14:56:53,365 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-15 14:56:53,365 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f2612fd8b50 state=pending>
2025-10-15 14:56:53,366 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-10-15 14:56:53,366 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f2612fc4b50>
2025-10-15 14:56:53,366 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f2612fc4b50>
2025-10-15 14:56:53,367 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 5c740a0f-a2c8-4f2c-ab44-b48f2bb4a7bb, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,367 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 5c740a0f-a2c8-4f2c-ab44-b48f2bb4a7bb, ghrun-w3odipbbdi.auto.internal:16824): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-15 14:56:53,369 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5c740a0f-a2c8-4f2c-ab44-b48f2bb4a7bb, ghrun-w3odipbbdi.auto.internal:16824): on response callback started
2025-10-15 14:56:53,370 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 5c740a0f-a2c8-4f2c-ab44-b48f2bb4a7bb, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ODA3NWE2MjctN2I3ZmI2NTctN2MyOWQyMGEtZjRkODkwZmE=" } } } }
2025-10-15 14:56:53,371 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2612fc4b50>
2025-10-15 14:56:53,371 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2612fc4b50>
2025-10-15 14:56:53,371 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5c740a0f-a2c8-4f2c-ab44-b48f2bb4a7bb, ghrun-w3odipbbdi.auto.internal:16824): on response callback success
2025-10-15 14:56:53,372 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 6842e970-c624-4560-bfda-d5e0d2f40053, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,372 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 6842e970-c624-4560-bfda-d5e0d2f40053, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=ODA3NWE2MjctN2I3ZmI2NTctN2MyOWQyMGEtZjRkODkwZmE=" yql_text: "create table `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` (key Int32, value String, primary key(key));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,410 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 6842e970-c624-4560-bfda-d5e0d2f40053, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS } }
2025-10-15 14:56:53,411 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2612fc4b50>
2025-10-15 14:56:53,412 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-15 14:56:53,412 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-15 14:56:53,412 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-15 14:56:53,413 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-15 14:56:53,413 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2612fc4b50>, reason: session-pool-terminated
2025-10-15 14:56:53,414 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f2612fc4b50> is no longer active. Current active count 0.
2025-10-15 14:56:53,414 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, eb3811a9-cb25-4709-bcc3-ce93d7422f54, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,414 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, eb3811a9-cb25-4709-bcc3-ce93d7422f54, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=ODA3NWE2MjctN2I3ZmI2NTctN2MyOWQyMGEtZjRkODkwZmE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-15 14:56:53,415 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2612fc4b50>
2025-10-15 14:56:53,416 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-15 14:56:53,416 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, eb3811a9-cb25-4709-bcc3-ce93d7422f54, ghrun-w3odipbbdi.auto.internal:16824): on response callback started
2025-10-15 14:56:53,417 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-15 14:56:53,417 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, eb3811a9-cb25-4709-bcc3-ce93d7422f54, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS } }
2025-10-15 14:56:53,418 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f2612fc28b0 state=pending>
2025-10-15 14:56:53,418 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, eb3811a9-cb25-4709-bcc3-ce93d7422f54, ghrun-w3odipbbdi.auto.internal:16824): on response callback success
2025-10-15 14:56:53,418 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-10-15 14:56:53,419 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f2612fc7990>
2025-10-15 14:56:53,419 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f2612fc7990>
2025-10-15 14:56:53,419 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 5b182da1-e745-4608-81ab-af086b358653, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,420 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 5b182da1-e745-4608-81ab-af086b358653, ghrun-w3odipbbdi.auto.internal:16824): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-15 14:56:53,421 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5b182da1-e745-4608-81ab-af086b358653, ghrun-w3odipbbdi.auto.internal:16824): on response callback started
2025-10-15 14:56:53,422 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 5b182da1-e745-4608-81ab-af086b358653, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" } } } }
2025-10-15 14:56:53,422 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2612fc7990>
2025-10-15 14:56:53,422 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2612fc7990>
2025-10-15 14:56:53,423 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 1d5b3858-0bce-4590-a803-3f76e724e0ca, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,424 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5b182da1-e745-4608-81ab-af086b358653, ghrun-w3odipbbdi.auto.internal:16824): on response callback success
2025-10-15 14:56:53,424 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 1d5b3858-0bce-4590-a803-3f76e724e0ca, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select * from `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` -- 0" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,446 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 1d5b3858-0bce-4590-a803-3f76e724e0ca, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "key" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: STRING } } } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-15 14:56:53,447 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 2e8e547e-fb21-4a91-ba6a-62ac646ae099, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,448 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 2e8e547e-fb21-4a91-ba6a-62ac646ae099, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select * from `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` -- 1" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,466 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 2e8e547e-fb21-4a91-ba6a-62ac646ae099, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "key" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: STRING } } } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-15 14:56:53,467 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 464ef9d9-28f1-4b45-a4b6-903b97dc67b4, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,468 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 464ef9d9-28f1-4b45-a4b6-903b97dc67b4, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select * from `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` -- 2" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,490 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 464ef9d9-28f1-4b45-a4b6-903b97dc67b4, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "key" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: STRING } } } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-15 14:56:53,491 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 05c4827d-d106-40f5-915a-ec6315dd9343, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,492 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 05c4827d-d106-40f5-915a-ec6315dd9343, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select * from `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` -- 3" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,512 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 05c4827d-d106-40f5-915a-ec6315dd9343, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "key" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: STRING } } } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-15 14:56:53,513 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ee3dccb0-b917-4cd0-87e4-d077ed8d3344, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,513 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ee3dccb0-b917-4cd0-87e4-d077ed8d3344, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select * from `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` -- 4" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,530 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ee3dccb0-b917-4cd0-87e4-d077ed8d3344, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "key" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: STRING } } } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-15 14:56:53,531 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 4d3bbdf1-5557-470a-a2bd-0e6e080fec43, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,532 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 4d3bbdf1-5557-470a-a2bd-0e6e080fec43, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select * from `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` -- 5" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,551 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 4d3bbdf1-5557-470a-a2bd-0e6e080fec43, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "key" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: STRING } } } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-15 14:56:53,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 10c677b2-fccf-4a8a-b986-01769eddfb7d, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,553 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 10c677b2-fccf-4a8a-b986-01769eddfb7d, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select * from `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` -- 6" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,573 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 10c677b2-fccf-4a8a-b986-01769eddfb7d, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "key" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: STRING } } } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-15 14:56:53,573 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, afd7c693-9217-4aa5-b1ce-64f1a9721cf5, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,574 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, afd7c693-9217-4aa5-b1ce-64f1a9721cf5, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select * from `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` -- 7" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,594 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, afd7c693-9217-4aa5-b1ce-64f1a9721cf5, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "key" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: STRING } } } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-15 14:56:53,595 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 8d0c086a-dd94-4646-abed-4841b0cf1bb6, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,596 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 8d0c086a-dd94-4646-abed-4841b0cf1bb6, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select * from `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` -- 8" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,614 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 8d0c086a-dd94-4646-abed-4841b0cf1bb6, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "key" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: STRING } } } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-15 14:56:53,615 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, fef25724-92cf-4c7e-ab07-c4c263a45047, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,616 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, fef25724-92cf-4c7e-ab07-c4c263a45047, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select * from `/Root/users/TestQueryMetricsUniqueQueries_test_case/table` -- 9" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 14:56:53,634 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, fef25724-92cf-4c7e-ab07-c4c263a45047, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "key" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: STRING } } } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-15 14:56:53,635 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2612fc7990>
2025-10-15 14:56:53,635 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-15 14:56:53,635 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-15 14:56:53,635 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-15 14:56:53,636 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-15 14:56:53,636 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2612fc7990>, reason: session-pool-terminated
2025-10-15 14:56:53,636 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f2612fc7990> is no longer active. Current active count 0.
2025-10-15 14:56:53,637 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 8e9e67d6-0127-48a2-a64d-e49fe3cd9d0d, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:56:53,637 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 8e9e67d6-0127-48a2-a64d-e49fe3cd9d0d, ghrun-w3odipbbdi.auto.internal:16824): request = { session_id: "ydb://session/3?node_id=50000&id=N2NjMmY5NGQtOWZjNjRhMzMtOGNkOTUxZTQtYTk3NTkyZjQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-15 14:56:53,638 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2612fc7990>
2025-10-15 14:56:53,638 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 8e9e67d6-0127-48a2-a64d-e49fe3cd9d0d, ghrun-w3odipbbdi.auto.internal:16824): on response callback started
2025-10-15 14:56:53,639 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-15 14:56:53,639 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 8e9e67d6-0127-48a2-a64d-e49fe3cd9d0d, ghrun-w3odipbbdi.auto.internal:16824): response = { operation { ready: true status: SUCCESS } }
2025-10-15 14:56:53,640 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 8e9e67d6-0127-48a2-a64d-e49fe3cd9d0d, ghrun-w3odipbbdi.auto.internal:16824): on response callback success
2025-10-15 14:58:03,640 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, bcc7d10e-1537-4e67-8b7c-5c9741503521, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:58:03,641 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, bcc7d10e-1537-4e67-8b7c-5c9741503521, ghrun-w3odipbbdi.auto.internal:16824): request = { query { yql_text: "select Count, SumReadBytes, SumRequestUnits from `/Root/users/TestQueryMetricsUniqueQueries_test_case/.sys/query_metrics_one_minute`;" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-15 14:58:03,642 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, bcc7d10e-1537-4e67-8b7c-5c9741503521, ghrun-w3odipbbdi.auto.internal:16824): response = { <_MultiThreadedRendezvous object> }
2025-10-15 14:58:03,668 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 35a897de-604f-4dd4-9937-98255bb6d86e, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:58:03,668 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 35a897de-604f-4dd4-9937-98255bb6d86e, ghrun-w3odipbbdi.auto.internal:16824): request = { query { yql_text: "select Duration, ReadBytes, CPUTime, RequestUnits from `/Root/users/TestQueryMetricsUniqueQueries_test_case/.sys/top_queries_by_duration_one_minute`;" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-15 14:58:03,670 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 35a897de-604f-4dd4-9937-98255bb6d86e, ghrun-w3odipbbdi.auto.internal:16824): response = { <_MultiThreadedRendezvous object> }
2025-10-15 14:58:03,692 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, bf816296-0723-4df6-9121-08c517b8879b, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:58:03,693 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, bf816296-0723-4df6-9121-08c517b8879b, ghrun-w3odipbbdi.auto.internal:16824): request = { query { yql_text: "select Duration, ReadBytes, CPUTime, RequestUnits from `/Root/users/TestQueryMetricsUniqueQueries_test_case/.sys/top_queries_by_duration_one_hour`;" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-15 14:58:03,694 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, bf816296-0723-4df6-9121-08c517b8879b, ghrun-w3odipbbdi.auto.internal:16824): response = { <_MultiThreadedRendezvous object> }
2025-10-15 14:58:03,715 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, c17e8e68-6098-48e6-aa26-efb7b72f29ec, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:58:03,716 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, c17e8e68-6098-48e6-aa26-efb7b72f29ec, ghrun-w3odipbbdi.auto.internal:16824): request = { query { yql_text: "select Duration, ReadBytes, CPUTime, RequestUnits from `/Root/users/TestQueryMetricsUniqueQueries_test_case/.sys/top_queries_by_read_bytes_one_minute`;" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-15 14:58:03,717 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, c17e8e68-6098-48e6-aa26-efb7b72f29ec, ghrun-w3odipbbdi.auto.internal:16824): response = { <_MultiThreadedRendezvous object> }
2025-10-15 14:58:03,739 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 4771b294-6d88-4165-80c7-ca69c230960e, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:58:03,739 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 4771b294-6d88-4165-80c7-ca69c230960e, ghrun-w3odipbbdi.auto.internal:16824): request = { query { yql_text: "select Duration, ReadBytes, CPUTime, RequestUnits from `/Root/users/TestQueryMetricsUniqueQueries_test_case/.sys/top_queries_by_read_bytes_one_hour`;" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-15 14:58:03,741 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 4771b294-6d88-4165-80c7-ca69c230960e, ghrun-w3odipbbdi.auto.internal:16824): response = { <_MultiThreadedRendezvous object> }
2025-10-15 14:58:03,762 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 3340f361-1546-4664-ac84-1716aee030f2, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:58:03,762 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 3340f361-1546-4664-ac84-1716aee030f2, ghrun-w3odipbbdi.auto.internal:16824): request = { query { yql_text: "select Duration, ReadBytes, CPUTime, RequestUnits from `/Root/users/TestQueryMetricsUniqueQueries_test_case/.sys/top_queries_by_cpu_time_one_minute`;" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-15 14:58:03,763 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 3340f361-1546-4664-ac84-1716aee030f2, ghrun-w3odipbbdi.auto.internal:16824): response = { <_MultiThreadedRendezvous object> }
2025-10-15 14:58:03,784 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 2d88a446-071e-4472-8873-567d5eacd6fe, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:58:03,785 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 2d88a446-071e-4472-8873-567d5eacd6fe, ghrun-w3odipbbdi.auto.internal:16824): request = { query { yql_text: "select Duration, ReadBytes, CPUTime, RequestUnits from `/Root/users/TestQueryMetricsUniqueQueries_test_case/.sys/top_queries_by_cpu_time_one_hour`;" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-15 14:58:03,786 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 2d88a446-071e-4472-8873-567d5eacd6fe, ghrun-w3odipbbdi.auto.internal:16824): response = { <_MultiThreadedRendezvous object> }
2025-10-15 14:58:03,806 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 33ce7094-35ce-4f31-a4ac-cfda36ed80eb, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:58:03,807 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 33ce7094-35ce-4f31-a4ac-cfda36ed80eb, ghrun-w3odipbbdi.auto.internal:16824): request = { query { yql_text: "select Duration, ReadBytes, CPUTime, RequestUnits from `/Root/users/TestQueryMetricsUniqueQueries_test_case/.sys/top_queries_by_request_units_one_minute`;" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-15 14:58:03,808 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 33ce7094-35ce-4f31-a4ac-cfda36ed80eb, ghrun-w3odipbbdi.auto.internal:16824): response = { <_MultiThreadedRendezvous object> }
2025-10-15 14:58:03,829 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 2e6baa5d-57cb-4e36-a1b4-2018b35e4dd5, ghrun-w3odipbbdi.auto.internal:16824): creating call state
2025-10-15 14:58:03,829 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 2e6baa5d-57cb-4e36-a1b4-2018b35e4dd5, ghrun-w3odipbbdi.auto.internal:16824): request = { query { yql_text: "select Duration, ReadBytes, CPUTime, RequestUnits from `/Root/users/TestQueryMetricsUniqueQueries_test_case/.sys/top_queries_by_request_units_one_hour`;" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-15 14:58:03,830 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 2e6baa5d-57cb-4e36-a1b4-2018b35e4dd5, ghrun-w3odipbbdi.auto.internal:16824): response = { <_MultiThreadedRendezvous object> }
2025-10-15 14:58:03,852 - DEBUG - ydb.topic - close: Close topic client
2025-10-15 14:58:03,852 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-15 14:58:03,853 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:30916
2025-10-15 14:58:03,853 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-w3odipbbdi.auto.internal:16824
2025-10-15 14:58:03,854 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-15 14:58:03,856 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-15 14:58:03,856 - DEBUG - __tests__.test_system_views - teardown_method: Remove database /Root/users/TestQueryMetricsUniqueQueries_test_case
2025-10-15 14:58:03,856 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/TestQueryMetricsUniqueQueries_test_case
2025-10-15 14:58:03,857 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/TestQueryMetricsUniqueQueries_test_case: send console operation, token None
2025-10-15 14:58:03,862 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/TestQueryMetricsUniqueQueries_test_case: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/TestQueryMetricsUniqueQueries_test_case&cmstid=72057594037936131&txid=1760540283860334&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-15 14:58:03,863 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/TestQueryMetricsUniqueQueries_test_case: wait console operation done
2025-10-15 14:58:04,366 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/TestQueryMetricsUniqueQueries_test_case: console operation done
2025-10-15 14:58:04,366 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/TestQueryMetricsUniqueQueries_test_case: wait tenant gone
2025-10-15 14:58:04,369 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/TestQueryMetricsUniqueQueries_test_case: tenant gone
2025-10-15 14:58:05,370 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:16824/1
2025-10-15 14:58:07,371 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:30916/1
2025-10-15 14:58:07,372 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21839
2025-10-15 14:58:07,372 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63911
2025-10-15 14:58:07,373 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1138
2025-10-15 14:58:07,373 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16486
2025-10-15 14:58:07,373 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16824
2025-10-15 14:58:07,373 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25806
2025-10-15 14:58:07,373 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15829
2025-10-15 14:58:07,374 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6160
2025-10-15 14:58:07,374 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30916
2025-10-15 14:58:07,374 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24848
