2025-11-05 12:24:57,479 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:24:57,479 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10]
2025-11-05 12:24:57,479 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:24:57,480 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-05 12:24:57,482 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10])
2025-11-05 12:24:57,483 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:57,483 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a312d8b6-0df2-4178-8b91-db881f5e9472, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:24:57,484 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a312d8b6-0df2-4178-8b91-db881f5e9472, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_10_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 10)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:57,486 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a312d8b6-0df2-4178-8b91-db881f5e9472, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:02,723 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:02,723 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_10_compression created
2025-11-05 12:25:02,724 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:02,725 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 032d51e7-30c9-4a88-9269-db69db6d5ec8, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:02,725 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 032d51e7-30c9-4a88-9269-db69db6d5ec8, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_10_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:02,727 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 032d51e7-30c9-4a88-9269-db69db6d5ec8, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:04,023 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:04,024 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:04,024 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b505e4a9-6ba7-45d9-8f7b-63501ce91e48, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:04,032 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b505e4a9-6ba7-45d9-8f7b-63501ce91e48, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:04,033 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b505e4a9-6ba7-45d9-8f7b-63501ce91e48, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:07,698 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:07,699 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:07,700 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 13ef7633-1e0d-4ead-a6e2-15aabaf5e879, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:07,701 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 13ef7633-1e0d-4ead-a6e2-15aabaf5e879, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:07,703 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 13ef7633-1e0d-4ead-a6e2-15aabaf5e879, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:09,407 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:09,408 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:09,408 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c7a1a57a-5ff6-4d77-b586-180f1997e842, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:09,409 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c7a1a57a-5ff6-4d77-b586-180f1997e842, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:09,412 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c7a1a57a-5ff6-4d77-b586-180f1997e842, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:10,900 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 12:25:10,901 - 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-11-05 12:25:10,918 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-05 12:25:10,919 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, b4bd14bb-d3fd-4b90-9888-2e75016099a6, localhost:16411): creating call state
2025-11-05 12:25:10,919 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, b4bd14bb-d3fd-4b90-9888-2e75016099a6, localhost:16411): request = { database: "/Root" }
2025-11-05 12:25:10,941 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, b4bd14bb-d3fd-4b90-9888-2e75016099a6, localhost:16411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-anwhr6cmha.auto.internal" port: 16411 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-11-05 12:25:10,942 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-anwhr6cmha.auto.internal:16411, location 1, ssl: False>]>
2025-11-05 12:25:10,953 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:16411
2025-11-05 12:25:12,187 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:12,188 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318048, 'BlobCount': 128}}
2025-11-05 12:25:12,189 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:12,189 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d9166955-13e1-4bb6-bef3-ae5fe7d0382d, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:12,192 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d9166955-13e1-4bb6-bef3-ae5fe7d0382d, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:12,193 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d9166955-13e1-4bb6-bef3-ae5fe7d0382d, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:15,484 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:15,485 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:15,486 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9f22f058-3cee-4567-ac00-f9db63381f42, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:15,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9f22f058-3cee-4567-ac00-f9db63381f42, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:15,491 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9f22f058-3cee-4567-ac00-f9db63381f42, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:16,435 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:16,436 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:16,437 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b11f550-dec6-4afb-800f-4f66abf97ad2, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:16,438 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b11f550-dec6-4afb-800f-4f66abf97ad2, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:16,442 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b11f550-dec6-4afb-800f-4f66abf97ad2, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:17,266 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:17,267 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637344, 'BlobCount': 256}}
2025-11-05 12:25:17,267 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:17,268 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 55262432-8b42-41fa-a11c-e424f12af918, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:17,269 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 55262432-8b42-41fa-a11c-e424f12af918, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:17,283 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 55262432-8b42-41fa-a11c-e424f12af918, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:19,840 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:19,841 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:19,841 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f7de9c13-1f4b-4868-861b-c7526012856e, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:19,842 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f7de9c13-1f4b-4868-861b-c7526012856e, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:19,844 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f7de9c13-1f4b-4868-861b-c7526012856e, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:20,370 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:20,371 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:20,372 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3b3ac30e-06e8-4cc6-af7a-89a6a4f0b11b, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:20,373 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3b3ac30e-06e8-4cc6-af7a-89a6a4f0b11b, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:20,374 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3b3ac30e-06e8-4cc6-af7a-89a6a4f0b11b, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:21,287 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:21,288 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956384, 'BlobCount': 384}}
2025-11-05 12:25:21,289 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:21,289 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d789277d-acee-4558-b9d6-a3ff162d6cb1, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:21,291 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d789277d-acee-4558-b9d6-a3ff162d6cb1, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:21,292 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d789277d-acee-4558-b9d6-a3ff162d6cb1, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:23,868 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:23,869 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:23,869 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f3576712-f288-48ea-a4e1-7393088a7ace, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:23,870 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f3576712-f288-48ea-a4e1-7393088a7ace, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:23,872 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f3576712-f288-48ea-a4e1-7393088a7ace, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:24,495 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:24,496 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:24,496 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08b04643-6618-46c6-84f6-47a4659717bc, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:24,497 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08b04643-6618-46c6-84f6-47a4659717bc, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:24,500 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08b04643-6618-46c6-84f6-47a4659717bc, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:25,601 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:25,601 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275744, 'BlobCount': 512}}
2025-11-05 12:25:25,602 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:25,602 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e0390595-d6d6-4746-9344-35d8d3cc41eb, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:25,603 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e0390595-d6d6-4746-9344-35d8d3cc41eb, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:25,604 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e0390595-d6d6-4746-9344-35d8d3cc41eb, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:28,456 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:28,457 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:28,458 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ad7a43bb-1f81-442e-8854-f6c5079162c8, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:28,458 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ad7a43bb-1f81-442e-8854-f6c5079162c8, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:28,460 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ad7a43bb-1f81-442e-8854-f6c5079162c8, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:29,035 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:29,036 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:29,037 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 27bfbd30-1ad1-4a5e-9ecb-6b08aa4a4f9c, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:29,038 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 27bfbd30-1ad1-4a5e-9ecb-6b08aa4a4f9c, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:29,044 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 27bfbd30-1ad1-4a5e-9ecb-6b08aa4a4f9c, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:30,202 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:30,203 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594704, 'BlobCount': 640}}
2025-11-05 12:25:30,203 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:30,204 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, efb1267c-0e4b-4764-b121-c5222160f64a, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:30,205 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, efb1267c-0e4b-4764-b121-c5222160f64a, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:30,207 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, efb1267c-0e4b-4764-b121-c5222160f64a, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:33,596 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:33,597 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:33,598 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a595918-c858-4070-a80b-d0777ddb33c6, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:33,599 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a595918-c858-4070-a80b-d0777ddb33c6, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:33,602 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a595918-c858-4070-a80b-d0777ddb33c6, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:34,312 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:34,313 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:34,314 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 010ba49a-dc7b-40b8-a1e6-be39689a3228, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:34,315 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 010ba49a-dc7b-40b8-a1e6-be39689a3228, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:34,322 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 010ba49a-dc7b-40b8-a1e6-be39689a3228, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:35,063 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:35,064 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913888, 'BlobCount': 768}}
2025-11-05 12:25:35,064 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:35,065 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cea5de42-cc19-4c7e-b815-9d5bfa8ab413, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:35,066 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cea5de42-cc19-4c7e-b815-9d5bfa8ab413, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:35,073 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cea5de42-cc19-4c7e-b815-9d5bfa8ab413, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:37,479 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:37,480 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:37,481 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8a8f5a1b-93d2-45a4-a1fc-b83ee5bddfb7, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:37,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8a8f5a1b-93d2-45a4-a1fc-b83ee5bddfb7, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:37,483 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8a8f5a1b-93d2-45a4-a1fc-b83ee5bddfb7, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:38,156 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:38,157 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:38,158 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5893182-cf31-4ef7-85e7-55f54b68dca8, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:38,159 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5893182-cf31-4ef7-85e7-55f54b68dca8, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:38,160 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5893182-cf31-4ef7-85e7-55f54b68dca8, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:39,269 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:39,270 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233024, 'BlobCount': 896}}
2025-11-05 12:25:39,270 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:39,271 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 152ff668-ec8a-4aaa-87cb-a62f7d3a1318, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:39,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 152ff668-ec8a-4aaa-87cb-a62f7d3a1318, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:39,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 152ff668-ec8a-4aaa-87cb-a62f7d3a1318, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:42,011 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:42,011 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:42,012 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 409689c1-facc-49ab-be09-f42bffaf7799, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:42,013 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 409689c1-facc-49ab-be09-f42bffaf7799, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:42,015 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 409689c1-facc-49ab-be09-f42bffaf7799, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:42,864 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:42,865 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:42,866 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c7ae33a2-202c-46a9-9c0e-dcf5a44e0418, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:42,868 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c7ae33a2-202c-46a9-9c0e-dcf5a44e0418, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:42,870 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c7ae33a2-202c-46a9-9c0e-dcf5a44e0418, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:43,862 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:43,863 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552512, 'BlobCount': 1024}}
2025-11-05 12:25:43,863 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:43,864 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 25a27702-0668-4238-a5b4-21588dcef7fd, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:43,865 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 25a27702-0668-4238-a5b4-21588dcef7fd, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:43,869 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 25a27702-0668-4238-a5b4-21588dcef7fd, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:46,534 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:46,534 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:46,535 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ded4e1b6-5fa1-4c3c-aa82-2173944fec6e, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:46,536 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ded4e1b6-5fa1-4c3c-aa82-2173944fec6e, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:46,537 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ded4e1b6-5fa1-4c3c-aa82-2173944fec6e, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:47,162 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:47,163 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:47,164 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1dcac06d-f036-4f7c-8eff-7695384c14c5, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:47,165 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1dcac06d-f036-4f7c-8eff-7695384c14c5, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:47,167 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1dcac06d-f036-4f7c-8eff-7695384c14c5, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:48,362 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:48,363 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871520, 'BlobCount': 1152}}
2025-11-05 12:25:48,364 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:48,364 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 64322ebb-c4d4-451e-93b1-c5f487a9cea5, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:48,365 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 64322ebb-c4d4-451e-93b1-c5f487a9cea5, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:48,376 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 64322ebb-c4d4-451e-93b1-c5f487a9cea5, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:50,708 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:50,709 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:50,710 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 088b4c1e-61dc-4162-b9f4-dfef8b69153e, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:50,711 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 088b4c1e-61dc-4162-b9f4-dfef8b69153e, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:50,712 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 088b4c1e-61dc-4162-b9f4-dfef8b69153e, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:51,491 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:51,492 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:51,493 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd50ff82-7532-4600-ba05-0c042c224c89, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:51,494 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd50ff82-7532-4600-ba05-0c042c224c89, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:51,496 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd50ff82-7532-4600-ba05-0c042c224c89, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:52,499 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:52,500 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191024, 'BlobCount': 1280}}
2025-11-05 12:25:52,500 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:52,501 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 430b3c96-1ef1-4dd3-bd59-f6c767850ef3, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:52,502 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 430b3c96-1ef1-4dd3-bd59-f6c767850ef3, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_10_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:52,503 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 430b3c96-1ef1-4dd3-bd59-f6c767850ef3, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:54,480 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:54,481 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:54,482 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 05b15d4d-f79b-4218-921f-3ed5a31f577c, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:54,483 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 05b15d4d-f79b-4218-921f-3ed5a31f577c, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:54,491 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 05b15d4d-f79b-4218-921f-3ed5a31f577c, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:54,999 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:55,000 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:55,001 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 781d9f10-738f-4ceb-b472-e77b8d2afa8c, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:55,001 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 781d9f10-738f-4ceb-b472-e77b8d2afa8c, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:55,004 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 781d9f10-738f-4ceb-b472-e77b8d2afa8c, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:55,463 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:55,464 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:25:55,464 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 663acdc8-c0d7-4825-b64a-c6688e7d9faf, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:25:55,465 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 663acdc8-c0d7-4825-b64a-c6688e7d9faf, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:55,471 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 663acdc8-c0d7-4825-b64a-c6688e7d9faf, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:01,843 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:11,937 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:11,938 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 21724d91-b83d-4d0c-bf5e-5c8d933b4b0b, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:26:11,939 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 21724d91-b83d-4d0c-bf5e-5c8d933b4b0b, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:11,940 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 21724d91-b83d-4d0c-bf5e-5c8d933b4b0b, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:15,193 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:25,211 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:25,212 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0487b40f-00c4-42ad-94cc-b4c65857219f, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:26:25,274 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0487b40f-00c4-42ad-94cc-b4c65857219f, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:25,276 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0487b40f-00c4-42ad-94cc-b4c65857219f, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:25,706 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 12:26:25,707 - 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-11-05 12:26:25,736 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-05 12:26:25,737 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, b23e0376-ae8e-48f4-b6e8-30cf7e42b8e0, localhost:16411): creating call state
2025-11-05 12:26:25,737 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, b23e0376-ae8e-48f4-b6e8-30cf7e42b8e0, localhost:16411): request = { database: "/Root" }
2025-11-05 12:26:26,021 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, b23e0376-ae8e-48f4-b6e8-30cf7e42b8e0, localhost:16411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-anwhr6cmha.auto.internal" port: 16411 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-11-05 12:26:26,022 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-anwhr6cmha.auto.internal:16411, location 1, ssl: False>]>
2025-11-05 12:26:26,023 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:16411
2025-11-05 12:26:26,885 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:36,890 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:36,891 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 318fb6e7-95e0-4d11-a884-729b503b2b76, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:26:36,892 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 318fb6e7-95e0-4d11-a884-729b503b2b76, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:36,894 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 318fb6e7-95e0-4d11-a884-729b503b2b76, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:38,030 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:38,043 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1465240)
2025-11-05 12:26:38,043 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:38,044 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5b02ae6c-6910-42a4-8d1d-7db59a3849c0, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:26:38,045 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5b02ae6c-6910-42a4-8d1d-7db59a3849c0, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:38,058 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5b02ae6c-6910-42a4-8d1d-7db59a3849c0, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:38,673 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:38,674 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:38,675 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7cd03f2e-f722-493b-9e44-16d6991c9216, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:26:38,676 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7cd03f2e-f722-493b-9e44-16d6991c9216, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:38,678 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7cd03f2e-f722-493b-9e44-16d6991c9216, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:40,062 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:50,070 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:50,071 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c3b1d990-be43-4d85-95c9-1c9da83a75bb, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:26:50,072 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c3b1d990-be43-4d85-95c9-1c9da83a75bb, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:50,074 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c3b1d990-be43-4d85-95c9-1c9da83a75bb, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:51,791 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:26:51,796 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1465240)
2025-11-05 12:26:51,797 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_10_compression` 8092160 / 1465240: 5.522753951571073
2025-11-05 12:26:51,799 - INFO - ya.test - pytest_runtest_teardown: Test teardown
