2025-11-14 06:50:42,882 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 06:50:42,883 - INFO - ya.test - pytest_runtest_setup: test_case
2025-11-14 06:50:42,883 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 06:50:42,883 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-14 06:50:42,974 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd
2025-11-14 06:50:42,975 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17701
2025-11-14 06:50:42,977 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/rl7h/0030c8/r3tmp/kikimr_cluster_qs_lz8to
2025-11-14 06:50:43,023 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd
2025-11-14 06:50:43,023 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11722
2025-11-14 06:50:43,024 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4234
2025-11-14 06:50:43,024 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3404
2025-11-14 06:50:43,024 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5144
2025-11-14 06:50:43,025 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/rl7h/0030c8/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-11-14 06:50:43,025 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/rl7h/0030c8/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/rl7h/0030c8/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_b12i1h70.log --grpc-port=11722 --mon-port=4234 --ic-port=17701
2025-11-14 06:50:43,025 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:11722/1, disk_size 68719476736
2025-11-14 06:50:43,026 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/rl7h/0030c8/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/rl7h/0030c8/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_b12i1h70.log', '--grpc-port=11722', '--mon-port=4234', '--ic-port=17701')' in '/home/runner/.ya/build/build_root/rl7h/0030c8/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-11-14 06:50:43,030 - DEBUG - ya.test - execute: Command pid: 1271272
2025-11-14 06:50:43,031 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:11722/1
2025-11-14 06:50:44,876 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd
2025-11-14 06:50:44,876 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:11722', '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: 17701\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-14 06:50:44,877 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-14 06:50:44,878 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-14 06:50:44,878 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:11722', '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: 17701\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-14 06:50:44,880 - DEBUG - ya.test - execute: Command pid: 1274305
2025-11-14 06:50:45,415 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) rc: 0
2025-11-14 06:50:45,415 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) elapsed time (sec): 0.5336644649505615
2025-11-14 06:50:45,415 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) idrss: 0
2025-11-14 06:50:45,415 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) inblock: 0
2025-11-14 06:50:45,415 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) isrss: 0
2025-11-14 06:50:45,416 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) ixrss: 0
2025-11-14 06:50:45,416 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) majflt: 0
2025-11-14 06:50:45,416 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) maxrss: 525764
2025-11-14 06:50:45,416 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) minflt: 19682
2025-11-14 06:50:45,416 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) msgrcv: 0
2025-11-14 06:50:45,416 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) msgsnd: 0
2025-11-14 06:50:45,416 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) nivcsw: 52
2025-11-14 06:50:45,416 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) nsignals: 0
2025-11-14 06:50:45,417 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) nswap: 0
2025-11-14 06:50:45,417 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) nvcsw: 113
2025-11-14 06:50:45,417 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) oublock: 8
2025-11-14 06:50:45,417 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) stime: 0.368229
2025-11-14 06:50:45,417 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) utime: 0.10968499999999999
2025-11-14 06:50:45,417 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) wtime: 0.536
2025-11-14 06:50:45,417 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-14 06:50:45,417 - DEBUG - ya.test - _save_outputs: Command (pid 1274305) errors:

2025-11-14 06:50:45,418 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd
2025-11-14 06:50:45,418 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:11722', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-14 06:50:45,419 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-14 06:50:45,419 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-14 06:50:45,420 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:11722', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-14 06:50:45,420 - DEBUG - ya.test - execute: Command pid: 1275299
2025-11-14 06:50:45,819 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) rc: 0
2025-11-14 06:50:45,819 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) elapsed time (sec): 0.3978097438812256
2025-11-14 06:50:45,820 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) idrss: 0
2025-11-14 06:50:45,820 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) inblock: 0
2025-11-14 06:50:45,820 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) isrss: 0
2025-11-14 06:50:45,820 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) ixrss: 0
2025-11-14 06:50:45,820 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) majflt: 0
2025-11-14 06:50:45,820 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) maxrss: 525768
2025-11-14 06:50:45,820 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) minflt: 19682
2025-11-14 06:50:45,820 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) msgrcv: 0
2025-11-14 06:50:45,821 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) msgsnd: 0
2025-11-14 06:50:45,821 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) nivcsw: 34
2025-11-14 06:50:45,821 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) nsignals: 0
2025-11-14 06:50:45,821 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) nswap: 0
2025-11-14 06:50:45,821 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) nvcsw: 140
2025-11-14 06:50:45,821 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) oublock: 8
2025-11-14 06:50:45,821 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) stime: 0.253857
2025-11-14 06:50:45,821 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) utime: 0.09122999999999999
2025-11-14 06:50:45,821 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) wtime: 0.399
2025-11-14 06:50:45,822 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-14 06:50:45,822 - DEBUG - ya.test - _save_outputs: Command (pid 1275299) errors:

2025-11-14 06:50:45,822 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-14 06:50:45,837 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-14 06:50:45,845 - DEBUG - __tests__.test_system_views - setup_method: Create database /Root/users/TestQueryMetricsUniqueQueries_test_case
2025-11-14 06:50:46,351 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd
2025-11-14 06:50:46,352 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8085
2025-11-14 06:50:46,352 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15912
2025-11-14 06:50:46,352 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61122
2025-11-14 06:50:46,353 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4490
2025-11-14 06:50:46,353 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19551
2025-11-14 06:50:46,353 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/rl7h/0030c8/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-11-14 06:50:46,354 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:11722 --tenant=/Root/users/TestQueryMetricsUniqueQueries_test_case --log-file-name=/home/runner/.ya/build/build_root/rl7h/0030c8/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_360mgk5y.log --grpc-port=8085 --mon-port=15912 --ic-port=61122
2025-11-14 06:50:46,354 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/rl7h/0030c8/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/rl7h/0030c8/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:11722', '--tenant=/Root/users/TestQueryMetricsUniqueQueries_test_case', '--log-file-name=/home/runner/.ya/build/build_root/rl7h/0030c8/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_360mgk5y.log', '--grpc-port=8085', '--mon-port=15912', '--ic-port=61122')' in '/home/runner/.ya/build/build_root/rl7h/0030c8/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-11-14 06:50:46,355 - DEBUG - ya.test - execute: Command pid: 1277488
2025-11-14 06:50:46,356 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:8085/1
2025-11-14 06:50:48,364 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_system_views.py::TestQueryMetricsUniqueQueries, test_name: test_case)
2025-11-14 06:50:48,365 - 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')]
2025-11-14 06:50:48,367 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 06:50:48,370 - 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')]
2025-11-14 06:50:48,383 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/TestQueryMetricsUniqueQueries_test_case
2025-11-14 06:50:48,384 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, c3a81780-c469-4ffa-b3e2-ca71b549e065, localhost:11722): creating call state
2025-11-14 06:50:48,385 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, c3a81780-c469-4ffa-b3e2-ca71b549e065, localhost:11722): request = { database: "/Root/users/TestQueryMetricsUniqueQueries_test_case" }
2025-11-14 06:50:48,392 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, c3a81780-c469-4ffa-b3e2-ca71b549e065, localhost:11722): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-iuibjpfjoa.auto.internal" port: 8085 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-11-14 06:50:48,392 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/TestQueryMetricsUniqueQueries_test_case: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-iuibjpfjoa.auto.internal:8085, location , ssl: False>]>
2025-11-14 06:50:48,393 - 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')]
2025-11-14 06:50:48,398 - INFO - ydb.connection - close: Closing channel for endpoint localhost:11722
2025-11-14 06:50:48,401 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-14 06:50:48,401 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f9c06df68b0 state=pending>
2025-11-14 06:50:48,401 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-14 06:50:48,401 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f9c06df5280>
2025-11-14 06:50:48,402 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f9c06df5280>
2025-11-14 06:50:48,402 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ca426d05-9632-48c6-8416-0e666264804b, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,403 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ca426d05-9632-48c6-8416-0e666264804b, ghrun-iuibjpfjoa.auto.internal:8085): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-14 06:50:48,406 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ca426d05-9632-48c6-8416-0e666264804b, ghrun-iuibjpfjoa.auto.internal:8085): on response callback started
2025-11-14 06:50:48,406 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ca426d05-9632-48c6-8416-0e666264804b, ghrun-iuibjpfjoa.auto.internal:8085): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ZDhlOTY1NmMtZjNhOTgwMjQtODU4MDM2Y2QtM2YzZGE5NDU=" } } } }
2025-11-14 06:50:48,406 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f9c06df5280>
2025-11-14 06:50:48,406 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f9c06df5280>
2025-11-14 06:50:48,407 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ca426d05-9632-48c6-8416-0e666264804b, ghrun-iuibjpfjoa.auto.internal:8085): on response callback success
2025-11-14 06:50:48,408 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 1a95e6a6-711c-4a2e-b349-fcd1635784f8, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,408 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 1a95e6a6-711c-4a2e-b349-fcd1635784f8, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=ZDhlOTY1NmMtZjNhOTgwMjQtODU4MDM2Y2QtM2YzZGE5NDU=" 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-11-14 06:50:48,471 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 1a95e6a6-711c-4a2e-b349-fcd1635784f8, ghrun-iuibjpfjoa.auto.internal:8085): response = { operation { ready: true status: SUCCESS } }
2025-11-14 06:50:48,472 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f9c06df5280>
2025-11-14 06:50:48,472 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-14 06:50:48,473 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-14 06:50:48,473 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-14 06:50:48,473 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-14 06:50:48,474 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f9c06df5280>, reason: session-pool-terminated
2025-11-14 06:50:48,474 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f9c06df5280> is no longer active. Current active count 0.
2025-11-14 06:50:48,474 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 2ebd8a28-01e0-4aab-80e8-1432e2b715dc, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,475 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 2ebd8a28-01e0-4aab-80e8-1432e2b715dc, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=ZDhlOTY1NmMtZjNhOTgwMjQtODU4MDM2Y2QtM2YzZGE5NDU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-14 06:50:48,476 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f9c06df5280>
2025-11-14 06:50:48,476 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-14 06:50:48,477 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 2ebd8a28-01e0-4aab-80e8-1432e2b715dc, ghrun-iuibjpfjoa.auto.internal:8085): on response callback started
2025-11-14 06:50:48,477 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 2ebd8a28-01e0-4aab-80e8-1432e2b715dc, ghrun-iuibjpfjoa.auto.internal:8085): response = { operation { ready: true status: SUCCESS } }
2025-11-14 06:50:48,477 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 2ebd8a28-01e0-4aab-80e8-1432e2b715dc, ghrun-iuibjpfjoa.auto.internal:8085): on response callback success
2025-11-14 06:50:48,478 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-14 06:50:48,478 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f9c06de0880 state=pending>
2025-11-14 06:50:48,478 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-14 06:50:48,479 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f9c06de1690>
2025-11-14 06:50:48,479 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f9c06de1690>
2025-11-14 06:50:48,479 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, cffd30b4-6c14-4985-8d60-53ee8813c4e2, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,480 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, cffd30b4-6c14-4985-8d60-53ee8813c4e2, ghrun-iuibjpfjoa.auto.internal:8085): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-14 06:50:48,486 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, cffd30b4-6c14-4985-8d60-53ee8813c4e2, ghrun-iuibjpfjoa.auto.internal:8085): on response callback started
2025-11-14 06:50:48,486 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, cffd30b4-6c14-4985-8d60-53ee8813c4e2, ghrun-iuibjpfjoa.auto.internal:8085): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" } } } }
2025-11-14 06:50:48,487 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, cffd30b4-6c14-4985-8d60-53ee8813c4e2, ghrun-iuibjpfjoa.auto.internal:8085): on response callback success
2025-11-14 06:50:48,487 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f9c06de1690>
2025-11-14 06:50:48,487 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f9c06de1690>
2025-11-14 06:50:48,487 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e0236c85-26ea-4219-ae32-87a2a4910479, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,488 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e0236c85-26ea-4219-ae32-87a2a4910479, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" 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-11-14 06:50:48,510 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e0236c85-26ea-4219-ae32-87a2a4910479, ghrun-iuibjpfjoa.auto.internal:8085): 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 } } } } } tx_meta { } } } } }
2025-11-14 06:50:48,511 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7cefbf41-2407-405a-b6c9-42c1c34fd59e, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,512 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7cefbf41-2407-405a-b6c9-42c1c34fd59e, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" 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-11-14 06:50:48,539 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7cefbf41-2407-405a-b6c9-42c1c34fd59e, ghrun-iuibjpfjoa.auto.internal:8085): 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 } } } } } tx_meta { } } } } }
2025-11-14 06:50:48,540 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 465c9b81-b510-407f-a6bc-de763db1edd2, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,541 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 465c9b81-b510-407f-a6bc-de763db1edd2, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" 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-11-14 06:50:48,563 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 465c9b81-b510-407f-a6bc-de763db1edd2, ghrun-iuibjpfjoa.auto.internal:8085): 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 } } } } } tx_meta { } } } } }
2025-11-14 06:50:48,564 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 87ab1298-d4ed-4559-9665-3a8fa52bcc6a, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,564 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 87ab1298-d4ed-4559-9665-3a8fa52bcc6a, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" 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-11-14 06:50:48,585 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 87ab1298-d4ed-4559-9665-3a8fa52bcc6a, ghrun-iuibjpfjoa.auto.internal:8085): 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 } } } } } tx_meta { } } } } }
2025-11-14 06:50:48,586 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 00d5ede5-0583-4074-87c0-d428d65b7f2c, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,587 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 00d5ede5-0583-4074-87c0-d428d65b7f2c, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" 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-11-14 06:50:48,607 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 00d5ede5-0583-4074-87c0-d428d65b7f2c, ghrun-iuibjpfjoa.auto.internal:8085): 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 } } } } } tx_meta { } } } } }
2025-11-14 06:50:48,608 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 1a87f753-cbea-4798-a618-7465aa7a7169, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,608 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 1a87f753-cbea-4798-a618-7465aa7a7169, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" 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-11-14 06:50:48,625 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 1a87f753-cbea-4798-a618-7465aa7a7169, ghrun-iuibjpfjoa.auto.internal:8085): 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 } } } } } tx_meta { } } } } }
2025-11-14 06:50:48,626 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a4b29496-8a33-4387-9ff0-28a564160c67, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,626 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a4b29496-8a33-4387-9ff0-28a564160c67, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" 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-11-14 06:50:48,646 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a4b29496-8a33-4387-9ff0-28a564160c67, ghrun-iuibjpfjoa.auto.internal:8085): 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 } } } } } tx_meta { } } } } }
2025-11-14 06:50:48,647 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7ff6ad54-4855-4904-9f25-49bc3b7a4694, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,648 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7ff6ad54-4855-4904-9f25-49bc3b7a4694, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" 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-11-14 06:50:48,665 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7ff6ad54-4855-4904-9f25-49bc3b7a4694, ghrun-iuibjpfjoa.auto.internal:8085): 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 } } } } } tx_meta { } } } } }
2025-11-14 06:50:48,665 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6a7de0ad-a4ff-4642-9f66-36ae406761c5, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,666 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6a7de0ad-a4ff-4642-9f66-36ae406761c5, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" 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-11-14 06:50:48,684 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6a7de0ad-a4ff-4642-9f66-36ae406761c5, ghrun-iuibjpfjoa.auto.internal:8085): 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 } } } } } tx_meta { } } } } }
2025-11-14 06:50:48,685 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, eb1f832e-7aaa-4cfc-b706-547b15018dee, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,686 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, eb1f832e-7aaa-4cfc-b706-547b15018dee, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" 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-11-14 06:50:48,705 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, eb1f832e-7aaa-4cfc-b706-547b15018dee, ghrun-iuibjpfjoa.auto.internal:8085): 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 } } } } } tx_meta { } } } } }
2025-11-14 06:50:48,706 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f9c06de1690>
2025-11-14 06:50:48,706 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-14 06:50:48,706 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-14 06:50:48,706 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-14 06:50:48,706 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f9c06de1690>, reason: session-pool-terminated
2025-11-14 06:50:48,706 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f9c06de1690> is no longer active. Current active count 0.
2025-11-14 06:50:48,707 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 881ddb0c-cf31-4257-bd4a-fce19af4755c, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:50:48,707 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-14 06:50:48,707 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 881ddb0c-cf31-4257-bd4a-fce19af4755c, ghrun-iuibjpfjoa.auto.internal:8085): request = { session_id: "ydb://session/3?node_id=50000&id=M2ZkMGFmYzctN2RjYmMwNjMtNDI4Y2NlMmQtMzgyNTNlYTI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-14 06:50:48,709 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f9c06de1690>
2025-11-14 06:50:48,709 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-14 06:50:48,710 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 881ddb0c-cf31-4257-bd4a-fce19af4755c, ghrun-iuibjpfjoa.auto.internal:8085): on response callback started
2025-11-14 06:50:48,710 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 881ddb0c-cf31-4257-bd4a-fce19af4755c, ghrun-iuibjpfjoa.auto.internal:8085): response = { operation { ready: true status: SUCCESS } }
2025-11-14 06:50:48,710 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 881ddb0c-cf31-4257-bd4a-fce19af4755c, ghrun-iuibjpfjoa.auto.internal:8085): on response callback success
2025-11-14 06:51:58,710 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 515377c4-bc77-408e-9ac3-2d0a15ddb76d, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:51:58,711 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 515377c4-bc77-408e-9ac3-2d0a15ddb76d, ghrun-iuibjpfjoa.auto.internal:8085): 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-11-14 06:51:58,712 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 515377c4-bc77-408e-9ac3-2d0a15ddb76d, ghrun-iuibjpfjoa.auto.internal:8085): response = { <_MultiThreadedRendezvous object> }
2025-11-14 06:51:58,730 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, aa8e4115-e697-44ad-bb6e-3bd93a3aa73a, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:51:58,731 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, aa8e4115-e697-44ad-bb6e-3bd93a3aa73a, ghrun-iuibjpfjoa.auto.internal:8085): 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-11-14 06:51:58,732 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, aa8e4115-e697-44ad-bb6e-3bd93a3aa73a, ghrun-iuibjpfjoa.auto.internal:8085): response = { <_MultiThreadedRendezvous object> }
2025-11-14 06:51:58,750 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, a8f28e1d-c21a-427e-80be-f86578358182, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:51:58,751 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, a8f28e1d-c21a-427e-80be-f86578358182, ghrun-iuibjpfjoa.auto.internal:8085): 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-11-14 06:51:58,752 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, a8f28e1d-c21a-427e-80be-f86578358182, ghrun-iuibjpfjoa.auto.internal:8085): response = { <_MultiThreadedRendezvous object> }
2025-11-14 06:51:58,769 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 396bb671-959e-4f1e-a712-3ad13ad30516, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:51:58,770 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 396bb671-959e-4f1e-a712-3ad13ad30516, ghrun-iuibjpfjoa.auto.internal:8085): 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-11-14 06:51:58,771 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 396bb671-959e-4f1e-a712-3ad13ad30516, ghrun-iuibjpfjoa.auto.internal:8085): response = { <_MultiThreadedRendezvous object> }
2025-11-14 06:51:58,787 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 9f0fa3ee-3ce7-4719-94c9-08da4f0c1723, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:51:58,788 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 9f0fa3ee-3ce7-4719-94c9-08da4f0c1723, ghrun-iuibjpfjoa.auto.internal:8085): 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-11-14 06:51:58,789 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 9f0fa3ee-3ce7-4719-94c9-08da4f0c1723, ghrun-iuibjpfjoa.auto.internal:8085): response = { <_MultiThreadedRendezvous object> }
2025-11-14 06:51:58,804 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, f8a7275a-6a72-4db5-846a-dc09370771cc, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:51:58,805 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, f8a7275a-6a72-4db5-846a-dc09370771cc, ghrun-iuibjpfjoa.auto.internal:8085): 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-11-14 06:51:58,806 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, f8a7275a-6a72-4db5-846a-dc09370771cc, ghrun-iuibjpfjoa.auto.internal:8085): response = { <_MultiThreadedRendezvous object> }
2025-11-14 06:51:58,821 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 86a978aa-9630-4049-8795-be4b0489d65c, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:51:58,822 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 86a978aa-9630-4049-8795-be4b0489d65c, ghrun-iuibjpfjoa.auto.internal:8085): 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-11-14 06:51:58,823 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 86a978aa-9630-4049-8795-be4b0489d65c, ghrun-iuibjpfjoa.auto.internal:8085): response = { <_MultiThreadedRendezvous object> }
2025-11-14 06:51:58,840 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 40f1d2e5-352b-4715-89fa-6b06e914e3e7, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:51:58,840 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 40f1d2e5-352b-4715-89fa-6b06e914e3e7, ghrun-iuibjpfjoa.auto.internal:8085): 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-11-14 06:51:58,841 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 40f1d2e5-352b-4715-89fa-6b06e914e3e7, ghrun-iuibjpfjoa.auto.internal:8085): response = { <_MultiThreadedRendezvous object> }
2025-11-14 06:51:58,857 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 8cae3ca2-d4cc-476d-8940-227a606be27c, ghrun-iuibjpfjoa.auto.internal:8085): creating call state
2025-11-14 06:51:58,857 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 8cae3ca2-d4cc-476d-8940-227a606be27c, ghrun-iuibjpfjoa.auto.internal:8085): 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-11-14 06:51:58,858 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 8cae3ca2-d4cc-476d-8940-227a606be27c, ghrun-iuibjpfjoa.auto.internal:8085): response = { <_MultiThreadedRendezvous object> }
2025-11-14 06:51:58,876 - INFO - ydb.connection - close: Closing channel for endpoint localhost:11722
2025-11-14 06:51:58,877 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-iuibjpfjoa.auto.internal:8085
2025-11-14 06:51:58,878 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-14 06:51:58,880 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-14 06:51:58,880 - DEBUG - __tests__.test_system_views - teardown_method: Remove database /Root/users/TestQueryMetricsUniqueQueries_test_case
2025-11-14 06:51:58,880 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/TestQueryMetricsUniqueQueries_test_case
2025-11-14 06:51:58,880 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/TestQueryMetricsUniqueQueries_test_case: send console operation, token None
2025-11-14 06:51:58,884 - 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=1763103118882329&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-14 06:51:58,885 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/TestQueryMetricsUniqueQueries_test_case: wait console operation done
2025-11-14 06:51:59,387 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/TestQueryMetricsUniqueQueries_test_case: console operation done
2025-11-14 06:51:59,387 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/TestQueryMetricsUniqueQueries_test_case: wait tenant gone
2025-11-14 06:51:59,389 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/TestQueryMetricsUniqueQueries_test_case: tenant gone
2025-11-14 06:52:00,390 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:8085/1
2025-11-14 06:52:02,391 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:11722/1
2025-11-14 06:52:02,392 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19551
2025-11-14 06:52:02,392 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4490
2025-11-14 06:52:02,392 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61122
2025-11-14 06:52:02,392 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15912
2025-11-14 06:52:02,393 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8085
2025-11-14 06:52:02,393 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5144
2025-11-14 06:52:02,393 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3404
2025-11-14 06:52:02,393 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4234
2025-11-14 06:52:02,393 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11722
2025-11-14 06:52:02,393 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17701
