2025-12-23 17:51:55,410 - INFO - ya.test.2335945  - pytest_runtest_setup: ####################################################################################################
2025-12-23 17:51:55,411 - INFO - ya.test.2335945  - pytest_runtest_setup: test_all_supported_compression[zstd_13_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 13]
2025-12-23 17:51:55,411 - INFO - ya.test.2335945  - pytest_runtest_setup: ####################################################################################################
2025-12-23 17:51:55,411 - INFO - ya.test.2335945  - pytest_runtest_setup: Test setup
2025-12-23 17:51:55,418 - INFO - ya.test.2335945  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_13_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 13])
2025-12-23 17:51:55,421 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:51:55,422 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, e6367fec-a3b4-4889-b670-41bbc830db58, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:51:55,423 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, e6367fec-a3b4-4889-b670-41bbc830db58, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_13_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 13)\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:51:55,429 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, e6367fec-a3b4-4889-b670-41bbc830db58, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:59,477 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:51:59,478 - INFO - __tests__.alter_compression.2335945  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_13_compression created
2025-12-23 17:51:59,479 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:51:59,479 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 5078ab0f-add3-4058-8dc7-c68aaee9c469, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:51:59,480 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 5078ab0f-add3-4058-8dc7-c68aaee9c469, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_13_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:59,485 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 5078ab0f-add3-4058-8dc7-c68aaee9c469, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:00,306 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335945  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 17:52:00,307 - DEBUG - ydb.connection.2335945  - 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:00,319 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335945  - context_resolve: Resolving endpoints for database /Root
2025-12-23 17:52:00,320 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ListEndpoints, 0b13cd22-df7a-4c23-965c-b1adebd175ad, localhost:30088): creating call state
2025-12-23 17:52:00,321 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ListEndpoints, 0b13cd22-df7a-4c23-965c-b1adebd175ad, localhost:30088): request = { database: "/Root" }
2025-12-23 17:52:00,330 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ListEndpoints, 0b13cd22-df7a-4c23-965c-b1adebd175ad, localhost:30088): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-yh5r7pxf7y.auto.internal" port: 30088 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:00,332 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335945  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-yh5r7pxf7y.auto.internal:30088, location 1, ssl: False>]>
2025-12-23 17:52:00,332 - DEBUG - ydb.connection.2335945  - close: Closing channel for endpoint localhost:30088
2025-12-23 17:52:00,754 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:00,754 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:00,809 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, fecdacba-5303-4d5d-bb88-63ef526fdf6c, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:00,810 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, fecdacba-5303-4d5d-bb88-63ef526fdf6c, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:00,812 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, fecdacba-5303-4d5d-bb88-63ef526fdf6c, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:03,691 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:03,692 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:03,693 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 7c486eb3-738d-43d1-b285-a2d781c1daf9, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:03,694 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 7c486eb3-738d-43d1-b285-a2d781c1daf9, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:03,695 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 7c486eb3-738d-43d1-b285-a2d781c1daf9, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:04,718 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:04,719 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:04,720 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 73ffafeb-965c-42b5-8bb1-c5dd6c51738d, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:04,721 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 73ffafeb-965c-42b5-8bb1-c5dd6c51738d, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:04,723 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 73ffafeb-965c-42b5-8bb1-c5dd6c51738d, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:06,820 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:06,821 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-12-23 17:52:06,821 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:06,822 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 8079d7b7-ba82-4a3f-bf22-228ff811fe39, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:06,823 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 8079d7b7-ba82-4a3f-bf22-228ff811fe39, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:06,825 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 8079d7b7-ba82-4a3f-bf22-228ff811fe39, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:10,032 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:10,033 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:10,034 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 1e9f6b47-738a-4bbd-8bbc-78410ef06f95, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:10,035 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 1e9f6b47-738a-4bbd-8bbc-78410ef06f95, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:10,040 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 1e9f6b47-738a-4bbd-8bbc-78410ef06f95, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:10,449 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:10,450 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:10,451 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 4caa8f89-d66c-4518-a228-3a4a57c660d5, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:10,452 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 4caa8f89-d66c-4518-a228-3a4a57c660d5, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:10,454 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 4caa8f89-d66c-4518-a228-3a4a57c660d5, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:11,041 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:11,042 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-12-23 17:52:11,042 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:11,043 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, e61478ca-0492-464e-bb8b-0cded06e76fb, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:11,045 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, e61478ca-0492-464e-bb8b-0cded06e76fb, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:11,048 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, e61478ca-0492-464e-bb8b-0cded06e76fb, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:14,681 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:14,682 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:14,683 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 73776696-5229-4ce7-92e4-c82075ad8ead, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:14,684 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 73776696-5229-4ce7-92e4-c82075ad8ead, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:14,686 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 73776696-5229-4ce7-92e4-c82075ad8ead, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:15,104 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:15,105 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:15,106 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 4d2cb71e-931e-498f-97e2-f0535531e558, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:15,107 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 4d2cb71e-931e-498f-97e2-f0535531e558, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:15,118 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 4d2cb71e-931e-498f-97e2-f0535531e558, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:15,572 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:15,573 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-12-23 17:52:15,576 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:15,577 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, f58cc8e3-26f5-4ca8-acd5-efd1ad622b53, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:15,578 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, f58cc8e3-26f5-4ca8-acd5-efd1ad622b53, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:15,588 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, f58cc8e3-26f5-4ca8-acd5-efd1ad622b53, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:18,886 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:18,886 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:18,887 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, f3a94474-1ccc-437d-9011-af6aa1d044fc, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:18,888 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, f3a94474-1ccc-437d-9011-af6aa1d044fc, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:18,892 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, f3a94474-1ccc-437d-9011-af6aa1d044fc, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:19,407 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:19,408 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:19,409 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 9676534b-5308-46a7-bbd2-9061b79ce20d, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:19,410 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 9676534b-5308-46a7-bbd2-9061b79ce20d, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:19,411 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 9676534b-5308-46a7-bbd2-9061b79ce20d, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:20,088 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:20,089 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-12-23 17:52:20,089 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:20,090 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 67cab833-846d-4a43-a618-79ef1f65dbbf, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:20,091 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 67cab833-846d-4a43-a618-79ef1f65dbbf, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:20,093 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 67cab833-846d-4a43-a618-79ef1f65dbbf, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:23,298 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:23,298 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:23,299 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 15d1e3f8-9a40-4d8b-9ad4-1ca6f6384dcb, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:23,300 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 15d1e3f8-9a40-4d8b-9ad4-1ca6f6384dcb, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:23,302 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 15d1e3f8-9a40-4d8b-9ad4-1ca6f6384dcb, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:23,665 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:23,666 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:23,667 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 2a1306e8-96d1-4773-9481-529c7f1c94fc, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:23,669 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 2a1306e8-96d1-4773-9481-529c7f1c94fc, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:23,671 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 2a1306e8-96d1-4773-9481-529c7f1c94fc, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:24,208 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:24,209 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-12-23 17:52:24,210 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:24,212 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 5b19d8ac-d7ab-4623-b11e-3c859c3886ad, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:24,213 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 5b19d8ac-d7ab-4623-b11e-3c859c3886ad, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:24,215 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 5b19d8ac-d7ab-4623-b11e-3c859c3886ad, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:26,659 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:26,660 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:26,661 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 02e2c517-464d-4948-91aa-fc79210c1377, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:26,662 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 02e2c517-464d-4948-91aa-fc79210c1377, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:26,666 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 02e2c517-464d-4948-91aa-fc79210c1377, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:26,916 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:26,917 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:26,917 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 851ebf67-4ad2-4813-9cae-03fc15dee78a, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:26,918 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 851ebf67-4ad2-4813-9cae-03fc15dee78a, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:26,920 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 851ebf67-4ad2-4813-9cae-03fc15dee78a, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:27,445 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:27,446 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-12-23 17:52:27,446 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:27,447 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, f0719183-644d-411f-be73-4749b08e5fb2, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:27,449 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, f0719183-644d-411f-be73-4749b08e5fb2, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:27,450 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, f0719183-644d-411f-be73-4749b08e5fb2, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:30,673 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:30,674 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:30,675 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 936aa3b5-5589-44c2-b4a2-13843c9a7301, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:30,676 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 936aa3b5-5589-44c2-b4a2-13843c9a7301, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:30,681 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 936aa3b5-5589-44c2-b4a2-13843c9a7301, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:31,248 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:31,249 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:31,250 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, f650b9ab-101d-456a-9710-1dd3ccb7870e, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:31,251 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, f650b9ab-101d-456a-9710-1dd3ccb7870e, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:31,253 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, f650b9ab-101d-456a-9710-1dd3ccb7870e, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:32,281 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:32,282 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-12-23 17:52:32,282 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:32,283 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 2956c115-2d56-45cc-81a5-40a0a991937e, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:32,288 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 2956c115-2d56-45cc-81a5-40a0a991937e, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:32,290 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 2956c115-2d56-45cc-81a5-40a0a991937e, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:35,602 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:35,603 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:35,606 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, daa7e52a-47f1-41af-8522-87b97e6d8f90, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:35,607 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, daa7e52a-47f1-41af-8522-87b97e6d8f90, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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,609 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, daa7e52a-47f1-41af-8522-87b97e6d8f90, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:35,907 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:35,908 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:35,909 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 63d66ec1-d365-4aa0-80fc-8423769f1a00, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:35,910 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 63d66ec1-d365-4aa0-80fc-8423769f1a00, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:35,912 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 63d66ec1-d365-4aa0-80fc-8423769f1a00, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:36,528 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:36,529 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-12-23 17:52:36,530 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:36,531 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 3641a92e-b81f-490a-b198-e7cc61966893, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:36,532 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 3641a92e-b81f-490a-b198-e7cc61966893, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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,538 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 3641a92e-b81f-490a-b198-e7cc61966893, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:39,854 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:39,855 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:39,856 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 7119e720-d9ae-41fa-8685-434e14bd9c6e, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:39,858 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 7119e720-d9ae-41fa-8685-434e14bd9c6e, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:39,862 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 7119e720-d9ae-41fa-8685-434e14bd9c6e, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:40,598 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:40,599 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:40,604 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 3b10b355-1bd6-4b07-b39a-50fd4900eaaa, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:40,605 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 3b10b355-1bd6-4b07-b39a-50fd4900eaaa, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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,607 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 3b10b355-1bd6-4b07-b39a-50fd4900eaaa, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:41,316 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:41,317 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-12-23 17:52:41,317 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:41,318 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, b190cf8d-abf0-446a-bb79-c7f30de8d4da, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:41,319 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, b190cf8d-abf0-446a-bb79-c7f30de8d4da, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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,321 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, b190cf8d-abf0-446a-bb79-c7f30de8d4da, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:45,129 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:45,129 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:45,130 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 335e120e-b0b1-46a1-bc50-c8677b1c08fc, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:45,131 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 335e120e-b0b1-46a1-bc50-c8677b1c08fc, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:45,136 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 335e120e-b0b1-46a1-bc50-c8677b1c08fc, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:45,470 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:45,472 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:45,473 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 8407850b-8cfb-437d-9556-c86a7b9064c1, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:45,474 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 8407850b-8cfb-437d-9556-c86a7b9064c1, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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,476 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 8407850b-8cfb-437d-9556-c86a7b9064c1, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:46,298 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:46,299 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-12-23 17:52:46,300 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:46,300 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 1b8f1d4c-de75-4bfc-bfc5-faf9aa3dfa6c, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:46,301 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 1b8f1d4c-de75-4bfc-bfc5-faf9aa3dfa6c, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_13_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:46,304 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 1b8f1d4c-de75-4bfc-bfc5-faf9aa3dfa6c, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:47,694 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:47,695 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:47,696 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 42f55805-450e-4796-9e6b-634988157fc4, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:47,697 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 42f55805-450e-4796-9e6b-634988157fc4, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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,698 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 42f55805-450e-4796-9e6b-634988157fc4, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:48,089 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:48,090 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:48,091 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 75dc9199-5f7e-4acb-acab-65682f1156e1, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:48,092 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 75dc9199-5f7e-4acb-acab-65682f1156e1, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:48,094 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 75dc9199-5f7e-4acb-acab-65682f1156e1, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:48,421 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:48,422 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:52:48,423 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, e8ca88c7-3d41-4fb4-8267-63c633b9e442, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:52:48,424 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, e8ca88c7-3d41-4fb4-8267-63c633b9e442, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_13_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:48,425 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, e8ca88c7-3d41-4fb4-8267-63c633b9e442, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:52,861 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:02,905 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:02,906 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 16e4f11e-e69a-4680-86bc-6f642c86d8b5, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:02,912 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 16e4f11e-e69a-4680-86bc-6f642c86d8b5, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_13_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:02,914 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 16e4f11e-e69a-4680-86bc-6f642c86d8b5, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:07,110 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:07,152 - INFO - root.2335945  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_13_compression`, volumes `value` (8000000, 1665104)
2025-12-23 17:53:07,152 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:07,153 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, fcab2a41-680f-4945-97a9-c7124002d839, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:07,161 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, fcab2a41-680f-4945-97a9-c7124002d839, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" 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_13_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:07,163 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, fcab2a41-680f-4945-97a9-c7124002d839, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:07,806 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:07,867 - ERROR - ya.test.2335945  - logreport: ydb/tests/olap/column_family/compression/alter_compression.py:194: in test_all_supported_compression
    assert table.get_portion_stat_by_tier()['__DEFAULT']['Rows'] == expected_raw // 8
E   assert 1015560 == (8000000 // 8)
2025-12-23 17:53:07,872 - INFO - ya.test.2335945  - pytest_runtest_teardown: Test teardown
