2025-12-23 17:53:07,879 - INFO - ya.test.2335945  - pytest_runtest_setup: ####################################################################################################
2025-12-23 17:53:07,880 - INFO - ya.test.2335945  - pytest_runtest_setup: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14]
2025-12-23 17:53:07,881 - INFO - ya.test.2335945  - pytest_runtest_setup: ####################################################################################################
2025-12-23 17:53:07,881 - INFO - ya.test.2335945  - pytest_runtest_setup: Test setup
2025-12-23 17:53:07,883 - INFO - ya.test.2335945  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14])
2025-12-23 17:53:07,884 - 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,885 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, e2eb2280-dc7a-4707-a86d-b992bdd81c78, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:07,886 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, e2eb2280-dc7a-4707-a86d-b992bdd81c78, 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_14_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 14)\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:53:07,887 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, e2eb2280-dc7a-4707-a86d-b992bdd81c78, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:12,960 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:12,961 - INFO - __tests__.alter_compression.2335945  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_14_compression created
2025-12-23 17:53:12,962 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:12,962 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 450e5259-fb69-4946-a02f-22664b774cf2, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:12,963 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 450e5259-fb69-4946-a02f-22664b774cf2, 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_14_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:12,966 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 450e5259-fb69-4946-a02f-22664b774cf2, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:15,676 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:15,677 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:15,678 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, de91989c-a0fd-41f9-95ba-ea937e34a8ac, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:15,679 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, de91989c-a0fd-41f9-95ba-ea937e34a8ac, 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_14_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:53:15,685 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, de91989c-a0fd-41f9-95ba-ea937e34a8ac, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:21,756 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:21,757 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:21,758 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, e332bf97-01d8-47ea-b134-3dd2deec64f8, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:21,759 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, e332bf97-01d8-47ea-b134-3dd2deec64f8, 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_14_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:21,761 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, e332bf97-01d8-47ea-b134-3dd2deec64f8, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:24,358 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:24,359 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:24,360 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 6f8d1694-9ce6-48a4-9063-958e389c52a3, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:24,361 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 6f8d1694-9ce6-48a4-9063-958e389c52a3, 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_14_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:53:24,363 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 6f8d1694-9ce6-48a4-9063-958e389c52a3, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:28,734 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:28,735 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-12-23 17:53:28,736 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:28,736 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 05c9d753-1638-4e66-9f03-6babfbcc04ea, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:28,737 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 05c9d753-1638-4e66-9f03-6babfbcc04ea, 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_14_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:53:28,739 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 05c9d753-1638-4e66-9f03-6babfbcc04ea, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:34,968 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335945  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 17:53:34,969 - 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:53:35,016 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335945  - context_resolve: Resolving endpoints for database /Root
2025-12-23 17:53:35,017 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ListEndpoints, fbffafd3-81a1-4b0c-8622-ab2ac5d5b5bb, localhost:30088): creating call state
2025-12-23 17:53:35,018 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ListEndpoints, fbffafd3-81a1-4b0c-8622-ab2ac5d5b5bb, localhost:30088): request = { database: "/Root" }
2025-12-23 17:53:35,024 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ListEndpoints, fbffafd3-81a1-4b0c-8622-ab2ac5d5b5bb, 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:53:35,027 - 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:53:35,028 - DEBUG - ydb.connection.2335945  - close: Closing channel for endpoint localhost:30088
2025-12-23 17:53:37,093 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:37,094 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:37,095 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, ce49f7f2-8fcb-480c-b503-6d9f04d6b1d6, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:37,096 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, ce49f7f2-8fcb-480c-b503-6d9f04d6b1d6, 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_14_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:37,097 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, ce49f7f2-8fcb-480c-b503-6d9f04d6b1d6, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:37,651 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:37,652 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:37,652 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 2ae29584-3a99-419b-87a8-af7c43a372aa, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:37,653 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 2ae29584-3a99-419b-87a8-af7c43a372aa, 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_14_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:53:37,672 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 2ae29584-3a99-419b-87a8-af7c43a372aa, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:39,225 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:39,226 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-12-23 17:53:39,226 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:39,227 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 90e2b744-6b69-4b73-bffe-b7456415861e, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:39,228 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 90e2b744-6b69-4b73-bffe-b7456415861e, 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_14_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:53:39,249 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 90e2b744-6b69-4b73-bffe-b7456415861e, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:45,093 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:45,093 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:45,094 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, e39a2a1a-ddf6-47bb-8f83-b10f3f1459c4, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:45,095 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, e39a2a1a-ddf6-47bb-8f83-b10f3f1459c4, 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_14_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:45,097 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, e39a2a1a-ddf6-47bb-8f83-b10f3f1459c4, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:45,542 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:45,543 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:45,544 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 4dd6ce67-17cc-44d9-a786-dfee67f69466, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:45,545 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 4dd6ce67-17cc-44d9-a786-dfee67f69466, 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_14_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:53:45,550 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 4dd6ce67-17cc-44d9-a786-dfee67f69466, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:46,188 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:46,189 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-12-23 17:53:46,190 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:46,190 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 46d0e949-b1a6-4687-9d59-e6d0f44e179e, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:46,191 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 46d0e949-b1a6-4687-9d59-e6d0f44e179e, 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_14_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:53:46,193 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 46d0e949-b1a6-4687-9d59-e6d0f44e179e, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:52,849 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:52,850 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:52,851 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 512e30b1-73ea-48c9-8f3e-01467a1616c0, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:52,852 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 512e30b1-73ea-48c9-8f3e-01467a1616c0, 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_14_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:52,853 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 512e30b1-73ea-48c9-8f3e-01467a1616c0, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:53,527 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:53,528 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:53,529 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 40b185a3-1dd4-4c04-ad1a-797ec4d7d884, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:53,530 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 40b185a3-1dd4-4c04-ad1a-797ec4d7d884, 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_14_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:53:53,532 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 40b185a3-1dd4-4c04-ad1a-797ec4d7d884, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:55,309 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:55,310 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-12-23 17:53:55,310 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:53:55,315 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 40e2a369-e401-43f7-9e5a-a6f0c685d972, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:53:55,316 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 40e2a369-e401-43f7-9e5a-a6f0c685d972, 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_14_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:53:55,329 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 40e2a369-e401-43f7-9e5a-a6f0c685d972, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:01,910 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:01,911 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:01,912 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, ac069e58-1202-4277-a9d9-0b23809e70f1, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:01,912 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, ac069e58-1202-4277-a9d9-0b23809e70f1, 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_14_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:54:01,915 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, ac069e58-1202-4277-a9d9-0b23809e70f1, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:02,817 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:02,865 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:02,866 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 84b13156-c60b-44c8-b764-77a033cde95d, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:02,867 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 84b13156-c60b-44c8-b764-77a033cde95d, 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_14_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:54:02,869 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 84b13156-c60b-44c8-b764-77a033cde95d, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:04,451 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:04,452 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-12-23 17:54:04,452 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:04,453 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 82fddb40-9b8d-4f8c-9f87-f71e736c7dc2, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:04,454 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 82fddb40-9b8d-4f8c-9f87-f71e736c7dc2, 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_14_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:54:04,458 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 82fddb40-9b8d-4f8c-9f87-f71e736c7dc2, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:07,997 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:07,998 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:07,999 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, cef2512e-02e2-48f8-a3b4-f53f999ed691, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:08,000 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, cef2512e-02e2-48f8-a3b4-f53f999ed691, 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_14_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:54:08,006 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, cef2512e-02e2-48f8-a3b4-f53f999ed691, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:08,478 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:08,479 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:08,480 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 18b9f323-3e8e-478b-9050-3e8ef20803da, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:08,481 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 18b9f323-3e8e-478b-9050-3e8ef20803da, 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_14_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:54:08,483 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 18b9f323-3e8e-478b-9050-3e8ef20803da, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:09,376 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:09,377 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-12-23 17:54:09,377 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:09,380 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, b1451bf3-4d00-41ab-9b35-72c7d25de2ff, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:09,381 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, b1451bf3-4d00-41ab-9b35-72c7d25de2ff, 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_14_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:54:09,382 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, b1451bf3-4d00-41ab-9b35-72c7d25de2ff, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:15,300 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:15,301 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:15,302 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, c1b14245-165e-4129-ab40-005b25a82562, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:15,303 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, c1b14245-165e-4129-ab40-005b25a82562, 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_14_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:54:15,305 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, c1b14245-165e-4129-ab40-005b25a82562, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:15,717 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:15,718 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:15,719 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 1325d5f2-f283-4d6d-8ab0-407102ae848b, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:15,724 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 1325d5f2-f283-4d6d-8ab0-407102ae848b, 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_14_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:54:15,726 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 1325d5f2-f283-4d6d-8ab0-407102ae848b, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:16,621 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:16,622 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-12-23 17:54:16,622 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:16,623 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 1a616ec8-ac0c-4761-902d-2157007f6da7, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:16,624 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 1a616ec8-ac0c-4761-902d-2157007f6da7, 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_14_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:54:16,629 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 1a616ec8-ac0c-4761-902d-2157007f6da7, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:20,914 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:20,915 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:20,916 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 7dd8202b-0e4b-4364-82a0-429514b28d80, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:20,917 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 7dd8202b-0e4b-4364-82a0-429514b28d80, 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_14_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:54:20,918 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 7dd8202b-0e4b-4364-82a0-429514b28d80, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:21,318 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:21,319 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:21,319 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 224e1b9e-a32d-49b3-8762-bc6d476420cb, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:21,320 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 224e1b9e-a32d-49b3-8762-bc6d476420cb, 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_14_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:54:21,322 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 224e1b9e-a32d-49b3-8762-bc6d476420cb, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:22,464 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:22,465 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-12-23 17:54:22,466 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:22,466 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, d646434a-cd71-4dc3-8ec2-5a89fee6d8f4, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:22,467 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, d646434a-cd71-4dc3-8ec2-5a89fee6d8f4, 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_14_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:54:22,480 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, d646434a-cd71-4dc3-8ec2-5a89fee6d8f4, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:27,772 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:27,776 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:27,777 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 781ee27e-fe11-4f79-b21f-8252be21dade, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:27,778 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 781ee27e-fe11-4f79-b21f-8252be21dade, 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_14_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:54:27,780 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 781ee27e-fe11-4f79-b21f-8252be21dade, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:28,326 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:28,327 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:28,327 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 47565efe-31b0-4e86-9e97-b6af81eb682a, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:28,328 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 47565efe-31b0-4e86-9e97-b6af81eb682a, 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_14_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:54:28,331 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 47565efe-31b0-4e86-9e97-b6af81eb682a, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:29,890 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:29,891 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-12-23 17:54:29,892 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:29,893 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 5ebea129-7d35-4921-bcf8-40dd1bcd4f70, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:29,893 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 5ebea129-7d35-4921-bcf8-40dd1bcd4f70, 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_14_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:54:29,895 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 5ebea129-7d35-4921-bcf8-40dd1bcd4f70, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:35,158 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:35,159 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:35,160 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, c30e13ca-f713-4e3c-be7e-e01ad843c516, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:35,161 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, c30e13ca-f713-4e3c-be7e-e01ad843c516, 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_14_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:54:35,163 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, c30e13ca-f713-4e3c-be7e-e01ad843c516, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:35,561 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:35,562 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:35,563 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, e5afd88f-c47a-4712-8558-d2c74d787649, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:35,564 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, e5afd88f-c47a-4712-8558-d2c74d787649, 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_14_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:54:35,566 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, e5afd88f-c47a-4712-8558-d2c74d787649, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:36,613 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:36,620 - INFO - __tests__.alter_compression.2335945  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-12-23 17:54:36,620 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:36,621 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, b8159f1a-7fa8-4990-8b77-172de95d7287, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:36,622 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, b8159f1a-7fa8-4990-8b77-172de95d7287, 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_14_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:54:36,630 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, b8159f1a-7fa8-4990-8b77-172de95d7287, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:37,901 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:37,902 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:37,903 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 67c4dba3-53e6-47a2-9cdb-749f1b714e66, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:37,904 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 67c4dba3-53e6-47a2-9cdb-749f1b714e66, 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_14_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:54:37,906 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 67c4dba3-53e6-47a2-9cdb-749f1b714e66, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:38,451 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:38,452 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:38,454 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 376c4249-5c9a-47c4-a4b4-dc994d1c5949, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:38,455 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 376c4249-5c9a-47c4-a4b4-dc994d1c5949, 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_14_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:54:38,456 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 376c4249-5c9a-47c4-a4b4-dc994d1c5949, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:39,079 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:39,080 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:39,082 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 09b4d728-6ae1-4d93-bd97-8e761024d007, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:39,083 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 09b4d728-6ae1-4d93-bd97-8e761024d007, 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_14_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:54:39,097 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 09b4d728-6ae1-4d93-bd97-8e761024d007, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:41,503 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335945  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 17:54:41,504 - 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:54:41,536 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335945  - context_resolve: Resolving endpoints for database /Root
2025-12-23 17:54:41,537 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ListEndpoints, fe59ad9d-a40a-441c-a8ce-c87295633b4d, localhost:30088): creating call state
2025-12-23 17:54:41,538 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ListEndpoints, fe59ad9d-a40a-441c-a8ce-c87295633b4d, localhost:30088): request = { database: "/Root" }
2025-12-23 17:54:41,657 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ListEndpoints, fe59ad9d-a40a-441c-a8ce-c87295633b4d, 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:54:41,658 - 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:54:41,659 - DEBUG - ydb.connection.2335945  - close: Closing channel for endpoint localhost:30088
2025-12-23 17:54:44,229 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:54,268 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:54,269 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 6e870a94-a748-4a2b-9e43-4cb6e2b6c28c, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:54,270 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 6e870a94-a748-4a2b-9e43-4cb6e2b6c28c, 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_14_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:54:54,272 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 6e870a94-a748-4a2b-9e43-4cb6e2b6c28c, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:54:59,791 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:59,876 - INFO - root.2335945  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1596216)
2025-12-23 17:54:59,877 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:54:59,878 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 0022d8ca-1fd8-4db0-b1a2-f5039952330c, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:54:59,879 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 0022d8ca-1fd8-4db0-b1a2-f5039952330c, 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_14_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:54:59,881 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 0022d8ca-1fd8-4db0-b1a2-f5039952330c, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:55:00,634 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:00,635 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:00,636 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, da48dabc-c6b6-40ce-890b-eb37ea9f6381, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:55:00,637 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, da48dabc-c6b6-40ce-890b-eb37ea9f6381, 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_14_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:55:00,640 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, da48dabc-c6b6-40ce-890b-eb37ea9f6381, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:55:05,874 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:15,978 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:15,979 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, ddd87238-f6c1-419c-a851-4d2445a7c4ac, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:55:15,980 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, ddd87238-f6c1-419c-a851-4d2445a7c4ac, 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_14_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:55:15,982 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, ddd87238-f6c1-419c-a851-4d2445a7c4ac, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:55:20,315 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:30,344 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:30,346 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, b2c0a02b-77ec-4866-8852-b9d8223361dd, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:55:30,346 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, b2c0a02b-77ec-4866-8852-b9d8223361dd, 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_14_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:55:30,348 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, b2c0a02b-77ec-4866-8852-b9d8223361dd, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:55:32,062 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:42,072 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:42,073 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, ea20d44c-4677-4be5-93bd-8f1ca9aa9082, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:55:42,074 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, ea20d44c-4677-4be5-93bd-8f1ca9aa9082, 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_14_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:55:42,076 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, ea20d44c-4677-4be5-93bd-8f1ca9aa9082, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:55:43,149 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:53,154 - DEBUG - ydb.query.pool.2335945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:53,155 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(ExecuteQuery, 1667b0c3-3b53-4486-ab6b-2ff231d632b3, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:55:53,156 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(ExecuteQuery, 1667b0c3-3b53-4486-ab6b-2ff231d632b3, 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_14_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:55:53,158 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(ExecuteQuery, 1667b0c3-3b53-4486-ab6b-2ff231d632b3, ghrun-yh5r7pxf7y.auto.internal:30088): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:55:54,135 - DEBUG - ydb.query.pool.2335945  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==
2025-12-23 17:55:54,140 - INFO - root.2335945  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1870856)
2025-12-23 17:55:54,141 - INFO - root.2335945  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_14_compression` 8092160 / 1870856: 4.325378329491954
2025-12-23 17:55:54,144 - INFO - ya.test.2335945  - pytest_runtest_teardown: Test teardown
2025-12-23 17:55:54,145 - DEBUG - ydb.connection.2335945  - _prepare_call: RpcState(DeleteSession, 02c83493-3f9c-4a4f-92d0-2d561c5cce9e, ghrun-yh5r7pxf7y.auto.internal:30088): creating call state
2025-12-23 17:55:54,146 - DEBUG - ydb.connection.2335945  - _log_request: RpcState(DeleteSession, 02c83493-3f9c-4a4f-92d0-2d561c5cce9e, ghrun-yh5r7pxf7y.auto.internal:30088): request = { session_id: "ydb://session/3?node_id=1&id=NjQxYmMwNDktNTBhOWRhYi0yZWM3Y2U5ZS1hNjk4YWQ1Yw==" }
2025-12-23 17:55:54,152 - DEBUG - ydb.connection.2335945  - _log_response: RpcState(DeleteSession, 02c83493-3f9c-4a4f-92d0-2d561c5cce9e, ghrun-yh5r7pxf7y.auto.internal:30088): response = { status: SUCCESS }
2025-12-23 17:55:54,154 - DEBUG - ydb.query.pool.2335945  - stop: All session were deleted.
2025-12-23 17:55:54,155 - DEBUG - ydb.topic.2335945  - close: Close topic client
2025-12-23 17:55:54,155 - DEBUG - ydb.topic.2335945  - close: Topic client was closed
2025-12-23 17:55:54,158 - DEBUG - ydb.connection.2335945  - close: Closing channel for endpoint localhost:30088
2025-12-23 17:55:54,165 - DEBUG - ydb.connection.2335945  - close: Closing channel for endpoint ghrun-yh5r7pxf7y.auto.internal:30088
2025-12-23 17:55:54,167 - INFO - ydb.pool.Discovery.2335945  - run: Successfully terminated discovery process
2025-12-23 17:55:54,168 - DEBUG - ydb.connection.2335945  - _rpc_error_handler: RpcState(AttachSession, b01c46cd-6934-4a29-be43-3da8547a6eff, ghrun-yh5r7pxf7y.auto.internal:30088): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-23 17:56:11,189 - INFO - ydb.tests.library.harness.kikimr_runner.2335945  - stop: Stopped node localhost:30088/1
2025-12-23 17:56:11,196 - DEBUG - library.python.filelock.2335945  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14506
2025-12-23 17:56:11,205 - DEBUG - library.python.filelock.2335945  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3756
2025-12-23 17:56:11,206 - DEBUG - library.python.filelock.2335945  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7961
2025-12-23 17:56:11,206 - DEBUG - library.python.filelock.2335945  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30088
2025-12-23 17:56:11,206 - DEBUG - library.python.filelock.2335945  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9522
