2025-10-16 17:19:57,326 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:19:57,327 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_9_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 9]
2025-10-16 17:19:57,327 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:19:57,327 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 17:19:57,335 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_9_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 9])
2025-10-16 17:19:57,336 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:19:57,341 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be700c2a-7b08-4c12-a07d-1fd49e30731e, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:19:57,342 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be700c2a-7b08-4c12-a07d-1fd49e30731e, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_9_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 9)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:57,344 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be700c2a-7b08-4c12-a07d-1fd49e30731e, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:01,456 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:01,457 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_9_compression created
2025-10-16 17:20:01,458 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:01,458 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 12ec3d25-30c8-4b59-9327-0c6334dd5820, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:01,459 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 12ec3d25-30c8-4b59-9327-0c6334dd5820, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_9_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:01,471 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 12ec3d25-30c8-4b59-9327-0c6334dd5820, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:02,998 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:02,998 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:02,999 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c821907d-b17f-4db3-a0b1-0fe595b9c350, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:03,000 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c821907d-b17f-4db3-a0b1-0fe595b9c350, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:03,002 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c821907d-b17f-4db3-a0b1-0fe595b9c350, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:05,403 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:05,403 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:05,404 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a9dcc9d2-e02f-4225-9350-f097ee152f8c, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:05,405 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a9dcc9d2-e02f-4225-9350-f097ee152f8c, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:05,408 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a9dcc9d2-e02f-4225-9350-f097ee152f8c, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:06,260 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:20:06,260 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:20:06,282 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:20:06,283 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 903c9a31-263b-4b7c-a459-d3b44d5e4fcc, localhost:9639): creating call state
2025-10-16 17:20:06,283 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 903c9a31-263b-4b7c-a459-d3b44d5e4fcc, localhost:9639): request = { database: "/Root" }
2025-10-16 17:20:06,289 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 903c9a31-263b-4b7c-a459-d3b44d5e4fcc, localhost:9639): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 9639 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" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-16 17:20:06,290 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:9639, location 1, ssl: False>]>
2025-10-16 17:20:06,290 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9639
2025-10-16 17:20:06,845 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:06,846 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:06,847 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0930e045-d329-4fb8-8cb1-c291feb87098, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:06,848 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0930e045-d329-4fb8-8cb1-c291feb87098, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:06,850 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0930e045-d329-4fb8-8cb1-c291feb87098, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:09,227 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:09,228 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318048, 'BlobCount': 128}}
2025-10-16 17:20:09,229 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:09,230 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d240cb1c-b212-4034-865a-786b8bb73d73, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:09,231 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d240cb1c-b212-4034-865a-786b8bb73d73, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:09,233 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d240cb1c-b212-4034-865a-786b8bb73d73, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:12,153 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:12,154 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:12,155 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 75d459c2-8865-4f7f-bba8-835e81b4b16a, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:12,156 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 75d459c2-8865-4f7f-bba8-835e81b4b16a, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:12,159 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 75d459c2-8865-4f7f-bba8-835e81b4b16a, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:12,810 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:12,811 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:12,812 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3895cacb-b08c-4950-8d63-adf97bb000df, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:12,813 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3895cacb-b08c-4950-8d63-adf97bb000df, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:12,815 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3895cacb-b08c-4950-8d63-adf97bb000df, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:13,586 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:13,587 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637344, 'BlobCount': 256}}
2025-10-16 17:20:13,587 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:13,588 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 459b98bd-fb50-4099-b975-f77c57e87a2e, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:13,609 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 459b98bd-fb50-4099-b975-f77c57e87a2e, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:13,611 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 459b98bd-fb50-4099-b975-f77c57e87a2e, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:16,402 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:16,404 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:16,405 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 74a94425-0902-4dd4-8bda-8f0d0cacb4c5, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:16,406 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 74a94425-0902-4dd4-8bda-8f0d0cacb4c5, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:16,417 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 74a94425-0902-4dd4-8bda-8f0d0cacb4c5, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:16,910 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:16,912 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:16,912 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 358ac9e5-64a1-4337-9b07-613dee426603, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:16,913 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 358ac9e5-64a1-4337-9b07-613dee426603, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:16,918 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 358ac9e5-64a1-4337-9b07-613dee426603, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:17,753 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:17,754 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956384, 'BlobCount': 384}}
2025-10-16 17:20:17,754 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:17,755 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 29b2fb9a-2aea-49a9-9864-4b3ae157fbb0, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:17,758 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 29b2fb9a-2aea-49a9-9864-4b3ae157fbb0, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:17,760 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 29b2fb9a-2aea-49a9-9864-4b3ae157fbb0, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:20,063 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:20,064 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:20,065 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 57b30d92-942f-4eee-ba96-a35657ac0656, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:20,066 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 57b30d92-942f-4eee-ba96-a35657ac0656, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:20,068 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 57b30d92-942f-4eee-ba96-a35657ac0656, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:20,512 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:20,513 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:20,514 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cc1978e5-87c6-4b78-89fb-4ba29d3f728e, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:20,515 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cc1978e5-87c6-4b78-89fb-4ba29d3f728e, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:20,518 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cc1978e5-87c6-4b78-89fb-4ba29d3f728e, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:21,466 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:21,467 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 418982, 'Portions': 255}}, blobs: {'__DEFAULT': {'Portions': 246, 'BlobSize': 1337128, 'BlobCount': 528}}
2025-10-16 17:20:21,468 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:21,469 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f46dab49-862f-4215-8642-75ee26de4c34, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:21,471 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f46dab49-862f-4215-8642-75ee26de4c34, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:21,483 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f46dab49-862f-4215-8642-75ee26de4c34, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:23,766 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:23,766 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:23,767 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b99c1b77-3700-4170-8cd7-6afdc1febe36, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:23,768 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b99c1b77-3700-4170-8cd7-6afdc1febe36, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:23,771 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b99c1b77-3700-4170-8cd7-6afdc1febe36, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:24,148 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:24,148 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:24,149 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c523fca-f7d7-4775-9c93-d07dd484c768, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:24,150 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c523fca-f7d7-4775-9c93-d07dd484c768, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:24,153 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c523fca-f7d7-4775-9c93-d07dd484c768, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:24,778 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:24,779 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 286}}, blobs: {'__DEFAULT': {'Portions': 286, 'BlobSize': 1594536, 'BlobCount': 616}}
2025-10-16 17:20:24,779 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:24,780 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a63f95f-5f33-41f2-a076-c2c453024b69, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:24,781 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a63f95f-5f33-41f2-a076-c2c453024b69, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:24,791 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a63f95f-5f33-41f2-a076-c2c453024b69, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:26,965 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:26,965 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:26,966 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c22abd2-67aa-4189-9fdd-4c9fe6292785, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:26,967 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c22abd2-67aa-4189-9fdd-4c9fe6292785, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:26,974 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c22abd2-67aa-4189-9fdd-4c9fe6292785, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:27,608 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:27,609 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:27,610 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cfb9dce1-0a17-48d8-81bc-72add4efec82, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:27,611 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cfb9dce1-0a17-48d8-81bc-72add4efec82, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:27,613 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cfb9dce1-0a17-48d8-81bc-72add4efec82, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:28,329 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:28,330 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 338}}, blobs: {'__DEFAULT': {'Portions': 338, 'BlobSize': 1911184, 'BlobCount': 726}}
2025-10-16 17:20:28,330 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:28,331 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 95377d75-d93b-4d52-b77d-56f06550e6ab, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:28,332 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 95377d75-d93b-4d52-b77d-56f06550e6ab, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:28,333 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 95377d75-d93b-4d52-b77d-56f06550e6ab, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:30,605 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:30,606 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:30,607 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 55edc0fd-5643-4186-bef8-e8cf0eb9556c, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:30,608 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 55edc0fd-5643-4186-bef8-e8cf0eb9556c, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:30,610 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 55edc0fd-5643-4186-bef8-e8cf0eb9556c, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:31,129 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:31,130 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:31,131 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5df89786-1a4e-49a8-92c9-9806983031fa, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:31,132 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5df89786-1a4e-49a8-92c9-9806983031fa, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:31,134 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5df89786-1a4e-49a8-92c9-9806983031fa, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:31,790 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:31,791 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 392}}, blobs: {'__DEFAULT': {'Portions': 392, 'BlobSize': 2228032, 'BlobCount': 838}}
2025-10-16 17:20:31,791 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:31,792 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ccea517a-420d-4baa-917a-a43b93d94f9a, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:31,793 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ccea517a-420d-4baa-917a-a43b93d94f9a, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:31,795 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ccea517a-420d-4baa-917a-a43b93d94f9a, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:34,077 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:34,077 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:34,078 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7ad91c5c-43d8-4ebb-b9a1-187ac14081b8, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:34,079 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7ad91c5c-43d8-4ebb-b9a1-187ac14081b8, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:34,082 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7ad91c5c-43d8-4ebb-b9a1-187ac14081b8, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:34,433 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:34,434 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:34,435 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3841c199-6776-4b0b-97d6-e594dd0acbee, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:34,436 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3841c199-6776-4b0b-97d6-e594dd0acbee, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:34,446 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3841c199-6776-4b0b-97d6-e594dd0acbee, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:35,194 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:35,195 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 426}}, blobs: {'__DEFAULT': {'Portions': 426, 'BlobSize': 2540008, 'BlobCount': 916}}
2025-10-16 17:20:35,196 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:35,197 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 60d0e334-c4b7-455b-a45f-27ca3a46731a, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:35,198 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 60d0e334-c4b7-455b-a45f-27ca3a46731a, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:35,200 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 60d0e334-c4b7-455b-a45f-27ca3a46731a, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:37,014 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:37,015 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:37,015 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ba669d8-4a63-4817-98e8-ef42bebf71ad, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:37,016 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ba669d8-4a63-4817-98e8-ef42bebf71ad, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:37,019 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ba669d8-4a63-4817-98e8-ef42bebf71ad, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:37,328 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:37,330 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:37,330 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1305b125-668c-4310-8a67-bb98d61ea696, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:37,332 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1305b125-668c-4310-8a67-bb98d61ea696, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:37,335 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1305b125-668c-4310-8a67-bb98d61ea696, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:38,301 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:38,302 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 483}}, blobs: {'__DEFAULT': {'Portions': 483, 'BlobSize': 2857200, 'BlobCount': 1032}}
2025-10-16 17:20:38,302 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:38,303 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b0bef6e8-73e1-4df1-bf4a-d248a03eb53b, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:38,303 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b0bef6e8-73e1-4df1-bf4a-d248a03eb53b, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:38,306 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b0bef6e8-73e1-4df1-bf4a-d248a03eb53b, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:40,657 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:40,657 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:40,658 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d034fde-86c8-4f2d-afb7-2987dfb96440, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:40,659 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d034fde-86c8-4f2d-afb7-2987dfb96440, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:40,661 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d034fde-86c8-4f2d-afb7-2987dfb96440, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:41,145 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:41,146 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:41,147 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 435c434e-3077-4687-8f8c-aa1e508944ec, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:41,148 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 435c434e-3077-4687-8f8c-aa1e508944ec, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:41,150 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 435c434e-3077-4687-8f8c-aa1e508944ec, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:42,073 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:42,074 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 515}}, blobs: {'__DEFAULT': {'Portions': 515, 'BlobSize': 3167968, 'BlobCount': 1104}}
2025-10-16 17:20:42,074 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:42,075 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c99ecf64-1353-4f85-8a22-39cc95b1968b, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:42,076 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c99ecf64-1353-4f85-8a22-39cc95b1968b, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_9_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:42,085 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c99ecf64-1353-4f85-8a22-39cc95b1968b, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:43,841 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:43,842 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:43,843 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 03fe38c5-d30f-41b7-995e-59be779d8fee, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:43,844 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 03fe38c5-d30f-41b7-995e-59be779d8fee, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:43,848 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 03fe38c5-d30f-41b7-995e-59be779d8fee, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:44,314 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:44,315 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:44,316 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2534c56-ec74-4cfe-a97b-9ede475f5e0c, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:44,317 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2534c56-ec74-4cfe-a97b-9ede475f5e0c, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:44,318 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2534c56-ec74-4cfe-a97b-9ede475f5e0c, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:44,770 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:44,771 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:20:44,772 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8613430b-fe0b-46f3-a91c-0ad09399beee, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:20:44,773 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8613430b-fe0b-46f3-a91c-0ad09399beee, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:44,781 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8613430b-fe0b-46f3-a91c-0ad09399beee, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:49,979 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:00,017 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:00,018 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c4145e8b-1cc3-407e-8df3-3fea301df77c, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:21:00,019 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c4145e8b-1cc3-407e-8df3-3fea301df77c, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:00,020 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c4145e8b-1cc3-407e-8df3-3fea301df77c, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:03,848 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:03,890 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_9_compression`, volumes `value` (8000000, 1567360)
2025-10-16 17:21:03,891 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:03,892 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f3345bdb-ba1b-415b-8ca7-e521f974f180, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:21:03,893 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f3345bdb-ba1b-415b-8ca7-e521f974f180, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:03,895 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f3345bdb-ba1b-415b-8ca7-e521f974f180, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:04,499 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:04,500 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:04,501 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3c4212da-7ce2-48b5-98c2-4b11bbf691c1, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:21:04,502 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3c4212da-7ce2-48b5-98c2-4b11bbf691c1, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:04,504 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3c4212da-7ce2-48b5-98c2-4b11bbf691c1, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:07,785 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:17,828 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:17,829 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d976646f-9d19-420d-a23d-62c21649c37b, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:21:17,830 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d976646f-9d19-420d-a23d-62c21649c37b, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:17,831 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d976646f-9d19-420d-a23d-62c21649c37b, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:20,219 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:30,252 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:30,253 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6ee289c6-2075-4b98-8a07-6bbde38fbe3b, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:21:30,253 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6ee289c6-2075-4b98-8a07-6bbde38fbe3b, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:30,255 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6ee289c6-2075-4b98-8a07-6bbde38fbe3b, ghrun-2kszfvc6cm.auto.internal:9639): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:32,475 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=
2025-10-16 17:21:32,495 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_9_compression`, volumes `value` (8000000, 1516440)
2025-10-16 17:21:32,495 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_9_compression` 8072864 / 1516440: 5.323563081955105
2025-10-16 17:21:32,497 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-16 17:21:32,498 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 44d799d6-eae3-4407-8bfe-7ab4153bf7df, ghrun-2kszfvc6cm.auto.internal:9639): creating call state
2025-10-16 17:21:32,499 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 44d799d6-eae3-4407-8bfe-7ab4153bf7df, ghrun-2kszfvc6cm.auto.internal:9639): request = { session_id: "ydb://session/3?node_id=1&id=NWFiYjA1YzMtYzE4MzVjZDMtMzUxYjVkMTAtMTIxNGQ5ZTU=" }
2025-10-16 17:21:32,504 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 44d799d6-eae3-4407-8bfe-7ab4153bf7df, ghrun-2kszfvc6cm.auto.internal:9639): response = { status: SUCCESS }
2025-10-16 17:21:32,506 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-16 17:21:32,506 - DEBUG - ydb.topic - close: Close topic client
2025-10-16 17:21:32,506 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-16 17:21:32,507 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9639
2025-10-16 17:21:32,507 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-2kszfvc6cm.auto.internal:9639
2025-10-16 17:21:32,508 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 00e6e38a-1ec4-4d43-ac3d-76ad309386e7, ghrun-2kszfvc6cm.auto.internal:9639): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-10-16 17:21:32,512 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-16 17:21:44,530 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:9639/1
2025-10-16 17:21:44,536 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16975
2025-10-16 17:21:44,536 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18466
2025-10-16 17:21:44,537 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31369
2025-10-16 17:21:44,537 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9639
2025-10-16 17:21:44,537 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61173
