2025-11-21 12:12:17,957 - INFO - ya.test.1729628  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:12:17,957 - INFO - ya.test.1729628  - pytest_runtest_setup: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10]
2025-11-21 12:12:17,957 - INFO - ya.test.1729628  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:12:17,958 - INFO - ya.test.1729628  - pytest_runtest_setup: Test setup
2025-11-21 12:12:17,961 - INFO - ya.test.1729628  - 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-21 12:12:17,961 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:17,962 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 8ac44296-88b0-4c37-9694-d4c4f17de0db, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:17,963 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 8ac44296-88b0-4c37-9694-d4c4f17de0db, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:17,965 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 8ac44296-88b0-4c37-9694-d4c4f17de0db, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:19,897 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1729628  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 12:12:19,898 - DEBUG - ydb.connection.1729628  - 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-21 12:12:19,916 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1729628  - context_resolve: Resolving endpoints for database /Root
2025-11-21 12:12:19,917 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ListEndpoints, fd570aa6-b9cc-4571-b489-be8cdf04e676, localhost:7283): creating call state
2025-11-21 12:12:19,918 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ListEndpoints, fd570aa6-b9cc-4571-b489-be8cdf04e676, localhost:7283): request = { database: "/Root" }
2025-11-21 12:12:22,850 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ListEndpoints, fd570aa6-b9cc-4571-b489-be8cdf04e676, localhost:7283): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ts5cnd6jre.auto.internal" port: 7283 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-21 12:12:22,851 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1729628  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ts5cnd6jre.auto.internal:7283, location 1, ssl: False>]>
2025-11-21 12:12:22,852 - DEBUG - ydb.connection.1729628  - close: Closing channel for endpoint localhost:7283
2025-11-21 12:12:23,417 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:23,418 - INFO - __tests__.alter_compression.1729628  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_10_compression created
2025-11-21 12:12:23,418 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:23,419 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 511a369b-ab92-4a46-a6ba-a9b08f583415, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:23,420 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 511a369b-ab92-4a46-a6ba-a9b08f583415, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:23,421 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 511a369b-ab92-4a46-a6ba-a9b08f583415, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:25,254 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:25,255 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:25,256 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 152e2766-3278-4084-bd76-64f387737f6d, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:25,257 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 152e2766-3278-4084-bd76-64f387737f6d, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:25,258 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 152e2766-3278-4084-bd76-64f387737f6d, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:27,566 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:27,567 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:27,568 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 848ebcd9-b4ea-4edf-b292-b0e91fea2a90, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:27,569 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 848ebcd9-b4ea-4edf-b292-b0e91fea2a90, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:27,571 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 848ebcd9-b4ea-4edf-b292-b0e91fea2a90, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:28,840 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:28,841 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:28,842 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 390f3c7e-3f51-4bde-8f6b-dd41ff5b7a40, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:28,843 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 390f3c7e-3f51-4bde-8f6b-dd41ff5b7a40, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:28,844 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 390f3c7e-3f51-4bde-8f6b-dd41ff5b7a40, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:31,004 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:31,004 - INFO - __tests__.alter_compression.1729628  - 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-21 12:12:31,034 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:31,035 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 90fa04f5-8224-44e2-a666-42ba59493139, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:31,036 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 90fa04f5-8224-44e2-a666-42ba59493139, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:31,037 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 90fa04f5-8224-44e2-a666-42ba59493139, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:33,256 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:33,256 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:33,257 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, adea2e8a-32cf-436c-9ce2-e8f2aa6a7d38, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:33,258 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, adea2e8a-32cf-436c-9ce2-e8f2aa6a7d38, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:33,259 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, adea2e8a-32cf-436c-9ce2-e8f2aa6a7d38, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:33,763 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:33,764 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:33,764 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 8cc3b577-a178-43f5-b433-da324c7e9c79, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:33,765 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 8cc3b577-a178-43f5-b433-da324c7e9c79, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:33,766 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 8cc3b577-a178-43f5-b433-da324c7e9c79, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:34,584 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:34,585 - INFO - __tests__.alter_compression.1729628  - 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-21 12:12:34,586 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:34,587 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 11f1b3b9-00f2-424b-9f1e-91cc954b3ef1, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:34,588 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 11f1b3b9-00f2-424b-9f1e-91cc954b3ef1, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:34,589 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 11f1b3b9-00f2-424b-9f1e-91cc954b3ef1, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:37,231 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:37,232 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:37,233 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 32fd7cdd-4382-47ab-a594-5dca95f35a26, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:37,234 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 32fd7cdd-4382-47ab-a594-5dca95f35a26, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:37,241 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 32fd7cdd-4382-47ab-a594-5dca95f35a26, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:37,777 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:37,777 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:37,778 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 41416e59-57ba-4857-b2ec-78428e60866d, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:37,779 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 41416e59-57ba-4857-b2ec-78428e60866d, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:37,780 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 41416e59-57ba-4857-b2ec-78428e60866d, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:38,272 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:38,273 - INFO - __tests__.alter_compression.1729628  - 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-21 12:12:38,273 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:38,274 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 38a44e39-91f0-4d83-8431-1995db563e51, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:38,275 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 38a44e39-91f0-4d83-8431-1995db563e51, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:38,276 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 38a44e39-91f0-4d83-8431-1995db563e51, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:40,119 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:40,120 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:40,121 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, cb79bd8d-a6f7-46c3-ad62-b7ee153b3113, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:40,122 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, cb79bd8d-a6f7-46c3-ad62-b7ee153b3113, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:40,123 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, cb79bd8d-a6f7-46c3-ad62-b7ee153b3113, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:40,532 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:40,532 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:40,533 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 784f63f9-a81c-45c6-9f30-a0aad0dda3e3, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:40,534 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 784f63f9-a81c-45c6-9f30-a0aad0dda3e3, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:40,539 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 784f63f9-a81c-45c6-9f30-a0aad0dda3e3, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:41,281 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:41,281 - INFO - __tests__.alter_compression.1729628  - 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-21 12:12:41,282 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:41,283 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, bf0643fd-887a-4ff8-acdb-bab363dc45da, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:41,283 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, bf0643fd-887a-4ff8-acdb-bab363dc45da, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:41,285 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, bf0643fd-887a-4ff8-acdb-bab363dc45da, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:43,500 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:43,501 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:43,501 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, bc7d82f4-bec2-40e5-9efc-56806dc5eb27, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:43,502 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, bc7d82f4-bec2-40e5-9efc-56806dc5eb27, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:43,503 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, bc7d82f4-bec2-40e5-9efc-56806dc5eb27, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:43,897 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:43,898 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:43,899 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 640d8a0d-6ea9-4f00-bb52-47529f5199a6, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:43,899 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 640d8a0d-6ea9-4f00-bb52-47529f5199a6, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:43,901 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 640d8a0d-6ea9-4f00-bb52-47529f5199a6, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:44,526 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:44,527 - INFO - __tests__.alter_compression.1729628  - 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-21 12:12:44,530 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:44,531 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, efb2dddc-112a-44d8-b9bc-48ed9efc2281, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:44,536 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, efb2dddc-112a-44d8-b9bc-48ed9efc2281, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:44,538 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, efb2dddc-112a-44d8-b9bc-48ed9efc2281, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:46,467 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:46,468 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:46,474 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 0e031126-2f5a-422f-a7d6-bd216d5e12e6, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:46,475 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 0e031126-2f5a-422f-a7d6-bd216d5e12e6, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:46,477 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 0e031126-2f5a-422f-a7d6-bd216d5e12e6, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:46,802 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:46,802 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:46,803 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 47ca5c2f-3cf2-4207-a29f-f31d6e94b373, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:46,804 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 47ca5c2f-3cf2-4207-a29f-f31d6e94b373, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:46,805 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 47ca5c2f-3cf2-4207-a29f-f31d6e94b373, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:47,513 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:47,513 - INFO - __tests__.alter_compression.1729628  - 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-21 12:12:47,514 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:47,514 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 694c1d6a-3372-4bd5-adf4-040c20542f45, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:47,515 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 694c1d6a-3372-4bd5-adf4-040c20542f45, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:47,516 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 694c1d6a-3372-4bd5-adf4-040c20542f45, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:49,544 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:49,547 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:49,549 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 2060b051-b9c7-41c0-9847-41e563fc1244, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:49,550 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 2060b051-b9c7-41c0-9847-41e563fc1244, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:49,552 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 2060b051-b9c7-41c0-9847-41e563fc1244, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:49,869 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:49,869 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:49,870 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, a315cc9e-aaae-4891-a1c6-9440a162d898, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:49,871 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, a315cc9e-aaae-4891-a1c6-9440a162d898, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:49,872 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, a315cc9e-aaae-4891-a1c6-9440a162d898, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:50,564 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:50,564 - INFO - __tests__.alter_compression.1729628  - 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-21 12:12:50,565 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:50,565 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 0eaf5268-f384-4175-a133-a7d289b0f9e5, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:50,566 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 0eaf5268-f384-4175-a133-a7d289b0f9e5, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:50,568 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 0eaf5268-f384-4175-a133-a7d289b0f9e5, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:52,435 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:52,436 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:52,437 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, bab03cd1-a481-4739-9bd1-23089de27567, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:52,437 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, bab03cd1-a481-4739-9bd1-23089de27567, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:52,439 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, bab03cd1-a481-4739-9bd1-23089de27567, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:52,728 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:52,729 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:52,730 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, e3e86b5f-f13b-4c1e-a99f-c0c42c1ce3f7, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:52,731 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, e3e86b5f-f13b-4c1e-a99f-c0c42c1ce3f7, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:52,732 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, e3e86b5f-f13b-4c1e-a99f-c0c42c1ce3f7, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:53,588 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:53,589 - INFO - __tests__.alter_compression.1729628  - 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-21 12:12:53,589 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:53,590 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, bb0cb70a-2305-4edc-99b2-e1a2c6b38bbe, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:53,591 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, bb0cb70a-2305-4edc-99b2-e1a2c6b38bbe, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:53,593 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, bb0cb70a-2305-4edc-99b2-e1a2c6b38bbe, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:55,275 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:55,276 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:55,276 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, e8c00331-801c-4ae4-b67b-dd49c2009a55, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:55,277 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, e8c00331-801c-4ae4-b67b-dd49c2009a55, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:55,279 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, e8c00331-801c-4ae4-b67b-dd49c2009a55, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:55,748 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:55,749 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:55,749 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, d2177d4d-64bc-4fdc-acee-0aed4dd51aba, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:55,750 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, d2177d4d-64bc-4fdc-acee-0aed4dd51aba, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:55,752 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, d2177d4d-64bc-4fdc-acee-0aed4dd51aba, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:56,456 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:56,457 - INFO - __tests__.alter_compression.1729628  - 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-21 12:12:56,457 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:56,458 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 7a7d598f-0802-4761-912f-8ac7db8f8d54, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:56,459 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 7a7d598f-0802-4761-912f-8ac7db8f8d54, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:56,464 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 7a7d598f-0802-4761-912f-8ac7db8f8d54, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:58,393 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:58,394 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:58,395 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 057940cc-3078-46c2-ae76-aa9f2729f082, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:58,395 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 057940cc-3078-46c2-ae76-aa9f2729f082, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:58,397 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 057940cc-3078-46c2-ae76-aa9f2729f082, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:58,740 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:58,741 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:58,742 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, d4f7e498-6300-4f84-9615-bba7b5cfa5d9, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:58,743 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, d4f7e498-6300-4f84-9615-bba7b5cfa5d9, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:58,744 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, d4f7e498-6300-4f84-9615-bba7b5cfa5d9, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:59,685 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:59,686 - INFO - __tests__.alter_compression.1729628  - 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-21 12:12:59,686 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:12:59,687 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 6adcc7ed-5f2b-4452-8642-92a2d3393c8d, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:12:59,688 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 6adcc7ed-5f2b-4452-8642-92a2d3393c8d, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:12:59,689 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 6adcc7ed-5f2b-4452-8642-92a2d3393c8d, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:01,451 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:01,452 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:01,452 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 0ffca93a-3b07-4fea-a973-6e17a1bb1610, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:13:01,453 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 0ffca93a-3b07-4fea-a973-6e17a1bb1610, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:13:01,455 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 0ffca93a-3b07-4fea-a973-6e17a1bb1610, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:01,877 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:01,878 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:01,879 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 380c923d-f561-47f7-ad13-0d826dd26696, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:13:01,880 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 380c923d-f561-47f7-ad13-0d826dd26696, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:13:01,881 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 380c923d-f561-47f7-ad13-0d826dd26696, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:02,337 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:02,338 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:02,338 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 3ec5b9d5-a1d9-4d47-9b90-7ee9fe1d530d, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:13:02,339 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 3ec5b9d5-a1d9-4d47-9b90-7ee9fe1d530d, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:13:02,341 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 3ec5b9d5-a1d9-4d47-9b90-7ee9fe1d530d, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:07,706 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:17,748 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:17,749 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, f37dc5ec-7afe-498d-adb5-9120e497b700, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:13:17,750 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, f37dc5ec-7afe-498d-adb5-9120e497b700, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:13:17,752 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, f37dc5ec-7afe-498d-adb5-9120e497b700, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:22,035 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:22,115 - INFO - root.1729628  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1595512)
2025-11-21 12:13:22,116 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:22,117 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 43ef285c-dbb2-4321-b7ed-78896d97afa4, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:13:22,118 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 43ef285c-dbb2-4321-b7ed-78896d97afa4, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:13:22,119 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 43ef285c-dbb2-4321-b7ed-78896d97afa4, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:22,555 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:22,556 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:22,556 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 7c546d54-eeeb-4190-a713-89f90e9d4d3e, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:13:22,557 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 7c546d54-eeeb-4190-a713-89f90e9d4d3e, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:13:22,559 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 7c546d54-eeeb-4190-a713-89f90e9d4d3e, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:26,505 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:36,537 - DEBUG - ydb.query.pool.1729628  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:36,538 - DEBUG - ydb.connection.1729628  - _prepare_call: RpcState(ExecuteQuery, 3f4e90c3-1883-4e1a-af4b-3bec3cbfeeae, ghrun-ts5cnd6jre.auto.internal:7283): creating call state
2025-11-21 12:13:36,539 - DEBUG - ydb.connection.1729628  - _log_request: RpcState(ExecuteQuery, 3f4e90c3-1883-4e1a-af4b-3bec3cbfeeae, ghrun-ts5cnd6jre.auto.internal:7283): request = { session_id: "ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=" 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-21 12:13:36,544 - DEBUG - ydb.connection.1729628  - _log_response: RpcState(ExecuteQuery, 3f4e90c3-1883-4e1a-af4b-3bec3cbfeeae, ghrun-ts5cnd6jre.auto.internal:7283): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:37,275 - DEBUG - ydb.query.pool.1729628  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjA5YjIwNDItNjRiM2IwM2MtODE2MzBiMTQtZTkzNDQ5OGM=
2025-11-21 12:13:37,282 - INFO - root.1729628  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1465240)
2025-11-21 12:13:37,283 - INFO - root.1729628  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_10_compression` 8092160 / 1465240: 5.522753951571073
2025-11-21 12:13:37,285 - INFO - ya.test.1729628  - pytest_runtest_teardown: Test teardown
