2025-12-23 17:52:22,364 - INFO - ya.test.2335951  - pytest_runtest_setup: ####################################################################################################
2025-12-23 17:52:22,365 - INFO - ya.test.2335951  - pytest_runtest_setup: test_all_supported_compression[zstd_5_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 5]
2025-12-23 17:52:22,365 - INFO - ya.test.2335951  - pytest_runtest_setup: ####################################################################################################
2025-12-23 17:52:22,365 - INFO - ya.test.2335951  - pytest_runtest_setup: Test setup
2025-12-23 17:52:22,367 - INFO - ya.test.2335951  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_5_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 5])
2025-12-23 17:52:22,368 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:22,369 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, aaaf8d5b-1cf3-496d-8b03-13a5eb4b672e, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:22,370 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, aaaf8d5b-1cf3-496d-8b03-13a5eb4b672e, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_5_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 5)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:22,372 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, aaaf8d5b-1cf3-496d-8b03-13a5eb4b672e, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:25,576 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:25,577 - INFO - __tests__.alter_compression.2335951  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_5_compression created
2025-12-23 17:52:25,578 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:25,578 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 82abff62-479e-4cc3-a2a8-91756ad8896a, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:25,579 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 82abff62-479e-4cc3-a2a8-91756ad8896a, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_5_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:25,582 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 82abff62-479e-4cc3-a2a8-91756ad8896a, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:26,498 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:26,499 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:26,499 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 0c79fe92-c94d-4018-ae5a-e2648fbcd9de, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:26,500 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 0c79fe92-c94d-4018-ae5a-e2648fbcd9de, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:26,502 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 0c79fe92-c94d-4018-ae5a-e2648fbcd9de, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:28,048 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:28,049 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:28,050 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 709badbd-ae03-40fc-b199-19102d475837, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:28,051 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 709badbd-ae03-40fc-b199-19102d475837, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:28,053 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 709badbd-ae03-40fc-b199-19102d475837, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:28,996 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:28,997 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:28,998 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 377f38ba-af97-430c-bcc0-053a8ac28b68, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:28,999 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 377f38ba-af97-430c-bcc0-053a8ac28b68, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:29,000 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 377f38ba-af97-430c-bcc0-053a8ac28b68, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:30,857 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335951  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 17:52:30,857 - DEBUG - ydb.connection.2335951  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 17:52:30,875 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335951  - context_resolve: Resolving endpoints for database /Root
2025-12-23 17:52:30,876 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ListEndpoints, 8c9a27f7-87e4-4906-a7fb-d0c3db0cf700, localhost:64039): creating call state
2025-12-23 17:52:30,878 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ListEndpoints, 8c9a27f7-87e4-4906-a7fb-d0c3db0cf700, localhost:64039): request = { database: "/Root" }
2025-12-23 17:52:30,888 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ListEndpoints, 8c9a27f7-87e4-4906-a7fb-d0c3db0cf700, localhost:64039): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-yh5r7pxf7y.auto.internal" port: 64039 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-12-23 17:52:30,889 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335951  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-yh5r7pxf7y.auto.internal:64039, location 1, ssl: False>]>
2025-12-23 17:52:30,889 - DEBUG - ydb.connection.2335951  - close: Closing channel for endpoint localhost:64039
2025-12-23 17:52:31,386 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:31,386 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318064, 'BlobCount': 128}}
2025-12-23 17:52:31,387 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:31,388 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 29d8dd55-7416-456d-9494-d6328a5140e5, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:31,389 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 29d8dd55-7416-456d-9494-d6328a5140e5, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:31,390 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 29d8dd55-7416-456d-9494-d6328a5140e5, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:33,110 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:33,111 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:33,112 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 680453c7-853d-4bd9-8608-db108da418ad, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:33,113 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 680453c7-853d-4bd9-8608-db108da418ad, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:33,114 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 680453c7-853d-4bd9-8608-db108da418ad, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:33,745 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:33,746 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:33,747 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, b2a4aeba-d39d-4a7c-a402-c7438598c75d, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:33,747 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, b2a4aeba-d39d-4a7c-a402-c7438598c75d, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:33,749 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, b2a4aeba-d39d-4a7c-a402-c7438598c75d, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:34,265 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:34,266 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637360, 'BlobCount': 256}}
2025-12-23 17:52:34,267 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:34,267 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 99260de7-f096-4082-ad6c-6f35b8e5e926, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:34,269 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 99260de7-f096-4082-ad6c-6f35b8e5e926, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:34,270 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 99260de7-f096-4082-ad6c-6f35b8e5e926, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:35,749 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:35,750 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:35,751 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 2ada4f11-0e2b-479a-aef1-f1efd0b9b1ca, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:35,754 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 2ada4f11-0e2b-479a-aef1-f1efd0b9b1ca, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:35,756 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 2ada4f11-0e2b-479a-aef1-f1efd0b9b1ca, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:36,086 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:36,087 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:36,087 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, bbe5a0f2-b072-4bdc-80f6-486595258db4, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:36,088 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, bbe5a0f2-b072-4bdc-80f6-486595258db4, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:36,090 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, bbe5a0f2-b072-4bdc-80f6-486595258db4, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:36,491 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:36,492 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956400, 'BlobCount': 384}}
2025-12-23 17:52:36,493 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:36,494 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 92e70e0f-157a-48ff-b590-614c3b42f79b, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:36,495 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 92e70e0f-157a-48ff-b590-614c3b42f79b, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:36,496 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 92e70e0f-157a-48ff-b590-614c3b42f79b, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:37,982 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:37,982 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:37,983 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 758af46f-ee3c-41b9-a10d-8276cd7fa57d, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:37,984 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 758af46f-ee3c-41b9-a10d-8276cd7fa57d, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:37,986 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 758af46f-ee3c-41b9-a10d-8276cd7fa57d, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:38,330 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:38,331 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:38,332 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 1e2c0a27-cca9-46ae-8ba6-7424717911fe, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:38,333 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 1e2c0a27-cca9-46ae-8ba6-7424717911fe, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:38,339 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 1e2c0a27-cca9-46ae-8ba6-7424717911fe, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:38,799 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:38,800 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275760, 'BlobCount': 512}}
2025-12-23 17:52:38,800 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:38,801 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 5670245f-3fbe-4b38-b891-2a1292b1309b, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:38,802 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 5670245f-3fbe-4b38-b891-2a1292b1309b, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:38,803 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 5670245f-3fbe-4b38-b891-2a1292b1309b, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:40,092 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:40,093 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:40,094 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 147f5add-1443-4b7e-9d2b-987193145ef8, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:40,095 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 147f5add-1443-4b7e-9d2b-987193145ef8, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:40,101 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 147f5add-1443-4b7e-9d2b-987193145ef8, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:40,535 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:40,536 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:40,537 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, b51c33e9-216e-4946-899b-b090366cc13b, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:40,538 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, b51c33e9-216e-4946-899b-b090366cc13b, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:40,540 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, b51c33e9-216e-4946-899b-b090366cc13b, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:41,062 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:41,063 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594720, 'BlobCount': 640}}
2025-12-23 17:52:41,064 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:41,064 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 4fc128ea-eeef-4752-9d6a-d7c80a7809f2, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:41,066 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 4fc128ea-eeef-4752-9d6a-d7c80a7809f2, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:41,069 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 4fc128ea-eeef-4752-9d6a-d7c80a7809f2, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:42,356 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:42,357 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:42,358 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, a1df907e-fa16-46fb-97c2-6c488ae82ad3, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:42,370 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, a1df907e-fa16-46fb-97c2-6c488ae82ad3, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:42,372 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, a1df907e-fa16-46fb-97c2-6c488ae82ad3, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:42,664 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:42,665 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:42,666 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, d3d95175-3f55-4936-aecd-6e04c6a3a9e3, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:42,667 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, d3d95175-3f55-4936-aecd-6e04c6a3a9e3, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:42,669 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, d3d95175-3f55-4936-aecd-6e04c6a3a9e3, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:43,286 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:43,287 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913904, 'BlobCount': 768}}
2025-12-23 17:52:43,287 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:43,288 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 2953b3de-1db5-4246-8e3f-454a0a816ee2, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:43,289 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 2953b3de-1db5-4246-8e3f-454a0a816ee2, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:43,291 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 2953b3de-1db5-4246-8e3f-454a0a816ee2, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:44,718 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:44,719 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:44,720 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, dae59bd4-c0be-4f63-8c7c-b2f0d140c97c, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:44,721 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, dae59bd4-c0be-4f63-8c7c-b2f0d140c97c, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:44,724 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, dae59bd4-c0be-4f63-8c7c-b2f0d140c97c, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:45,159 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:45,161 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:45,161 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, a28aff61-d830-4c5b-a29f-06155a0ed84a, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:45,163 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, a28aff61-d830-4c5b-a29f-06155a0ed84a, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:45,167 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, a28aff61-d830-4c5b-a29f-06155a0ed84a, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:46,042 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:46,043 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233040, 'BlobCount': 896}}
2025-12-23 17:52:46,043 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:46,044 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 14b3de8b-d3a4-406a-89a9-384dcad5a078, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:46,045 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 14b3de8b-d3a4-406a-89a9-384dcad5a078, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:46,055 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 14b3de8b-d3a4-406a-89a9-384dcad5a078, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:47,689 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:47,690 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:47,691 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 51120748-07fa-4b85-828e-04339bc76788, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:47,693 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 51120748-07fa-4b85-828e-04339bc76788, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:47,695 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 51120748-07fa-4b85-828e-04339bc76788, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:48,055 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:48,056 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:48,057 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, caf8214b-f4ac-4ccb-9178-9f16c590dca0, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:48,058 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, caf8214b-f4ac-4ccb-9178-9f16c590dca0, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:48,062 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, caf8214b-f4ac-4ccb-9178-9f16c590dca0, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:48,837 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:48,838 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552528, 'BlobCount': 1024}}
2025-12-23 17:52:48,838 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:48,839 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 531cf320-449c-49bd-bd9d-457784c451dc, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:48,840 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 531cf320-449c-49bd-bd9d-457784c451dc, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:48,842 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 531cf320-449c-49bd-bd9d-457784c451dc, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:50,286 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:50,381 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:50,382 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, fbc65a34-8a64-469a-99a7-d2521ef6811f, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:50,383 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, fbc65a34-8a64-469a-99a7-d2521ef6811f, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:50,385 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, fbc65a34-8a64-469a-99a7-d2521ef6811f, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:50,907 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:50,908 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:50,909 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 2bb49e45-1aa7-4680-bba9-0287cb24e97a, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:50,910 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 2bb49e45-1aa7-4680-bba9-0287cb24e97a, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:50,912 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 2bb49e45-1aa7-4680-bba9-0287cb24e97a, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:51,977 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:51,977 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871536, 'BlobCount': 1152}}
2025-12-23 17:52:51,978 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:51,979 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 6f04fd1e-a91d-471c-be48-68582554c087, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:51,980 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 6f04fd1e-a91d-471c-be48-68582554c087, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:51,982 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 6f04fd1e-a91d-471c-be48-68582554c087, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:53,634 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:53,634 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:53,635 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, ac30a0a5-9c4f-48b4-975d-9395f39442f0, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:53,636 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, ac30a0a5-9c4f-48b4-975d-9395f39442f0, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:53,638 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, ac30a0a5-9c4f-48b4-975d-9395f39442f0, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:54,163 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:54,164 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:54,165 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, fd88488e-c044-4f64-b213-3f0aaeb69813, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:54,166 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, fd88488e-c044-4f64-b213-3f0aaeb69813, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:54,169 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, fd88488e-c044-4f64-b213-3f0aaeb69813, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:55,570 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:55,571 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191040, 'BlobCount': 1280}}
2025-12-23 17:52:55,572 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:55,573 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 3a091abe-cfc5-4e38-b27f-8a574e8aabe7, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:55,574 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 3a091abe-cfc5-4e38-b27f-8a574e8aabe7, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_5_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:55,583 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 3a091abe-cfc5-4e38-b27f-8a574e8aabe7, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:57,130 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:57,131 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:57,132 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, e1c822ab-13f3-4c19-9ea7-3350682083eb, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:57,133 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, e1c822ab-13f3-4c19-9ea7-3350682083eb, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:57,135 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, e1c822ab-13f3-4c19-9ea7-3350682083eb, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:57,436 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:57,437 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:57,438 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, d5f1e80f-c173-4998-a41a-576e888d77d1, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:57,439 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, d5f1e80f-c173-4998-a41a-576e888d77d1, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:57,441 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, d5f1e80f-c173-4998-a41a-576e888d77d1, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:57,772 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:57,773 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:57,774 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 4b8565af-48c2-4212-8589-1c532c13fef4, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:57,775 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 4b8565af-48c2-4212-8589-1c532c13fef4, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:57,776 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 4b8565af-48c2-4212-8589-1c532c13fef4, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:02,831 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:53:13,006 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:53:13,008 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 3b2cf3c8-55a6-4bac-b1df-81dcfbcb155f, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:53:13,009 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 3b2cf3c8-55a6-4bac-b1df-81dcfbcb155f, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:13,010 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 3b2cf3c8-55a6-4bac-b1df-81dcfbcb155f, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:17,769 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:53:17,864 - INFO - root.2335951  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1595520)
2025-12-23 17:53:17,865 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:53:17,866 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, ac383e74-4afd-4a86-a7f1-cc339437dfdd, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:53:17,867 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, ac383e74-4afd-4a86-a7f1-cc339437dfdd, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:17,869 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, ac383e74-4afd-4a86-a7f1-cc339437dfdd, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:18,189 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:53:18,190 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:53:18,191 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, f05d1086-ca2b-46f2-ad91-102cc01fa91d, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:53:18,192 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, f05d1086-ca2b-46f2-ad91-102cc01fa91d, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:18,193 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, f05d1086-ca2b-46f2-ad91-102cc01fa91d, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:25,290 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:53:35,392 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:53:35,393 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, b9962244-994a-4fb9-8c21-cd2c8663ea1b, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:53:35,395 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, b9962244-994a-4fb9-8c21-cd2c8663ea1b, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:35,420 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, b9962244-994a-4fb9-8c21-cd2c8663ea1b, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:41,816 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:53:41,883 - INFO - root.2335951  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1531304)
2025-12-23 17:53:41,921 - INFO - root.2335951  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_5_compression` 8092160 / 1531304: 5.28448955922534
2025-12-23 17:53:41,950 - INFO - ya.test.2335951  - pytest_runtest_teardown: Test teardown
2025-12-23 17:53:41,951 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(DeleteSession, 9f929c5b-89c5-429d-9176-255c31166539, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:53:41,952 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(DeleteSession, 9f929c5b-89c5-429d-9176-255c31166539, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" }
2025-12-23 17:53:41,978 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(DeleteSession, 9f929c5b-89c5-429d-9176-255c31166539, ghrun-yh5r7pxf7y.auto.internal:64039): response = { status: SUCCESS }
2025-12-23 17:53:41,980 - DEBUG - ydb.query.pool.2335951  - stop: All session were deleted.
2025-12-23 17:53:41,980 - DEBUG - ydb.topic.2335951  - close: Close topic client
2025-12-23 17:53:42,021 - DEBUG - ydb.topic.2335951  - close: Topic client was closed
2025-12-23 17:53:42,022 - DEBUG - ydb.connection.2335951  - close: Closing channel for endpoint localhost:64039
2025-12-23 17:53:42,023 - DEBUG - ydb.connection.2335951  - close: Closing channel for endpoint ghrun-yh5r7pxf7y.auto.internal:64039
2025-12-23 17:53:42,024 - INFO - ydb.pool.Discovery.2335951  - run: Successfully terminated discovery process
2025-12-23 17:53:42,029 - DEBUG - ydb.connection.2335951  - _rpc_error_handler: RpcState(AttachSession, eb90b19c-1b7b-4a4e-a39a-a81fabeb2008, ghrun-yh5r7pxf7y.auto.internal:64039): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-23 17:54:09,070 - INFO - ydb.tests.library.harness.kikimr_runner.2335951  - stop: Stopped node localhost:64039/1
2025-12-23 17:54:09,085 - DEBUG - library.python.filelock.2335951  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17443
2025-12-23 17:54:09,085 - DEBUG - library.python.filelock.2335951  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64339
2025-12-23 17:54:09,086 - DEBUG - library.python.filelock.2335951  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27442
2025-12-23 17:54:09,094 - DEBUG - library.python.filelock.2335951  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64039
2025-12-23 17:54:09,094 - DEBUG - library.python.filelock.2335951  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4972
