2025-11-19 11:25:34,258 - INFO - ya.test.421771  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:25:34,272 - INFO - ya.test.421771  - pytest_runtest_setup: test_all_supported_compression[zstd_17_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 17]
2025-11-19 11:25:34,273 - INFO - ya.test.421771  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:25:34,273 - INFO - ya.test.421771  - pytest_runtest_setup: Test setup
2025-11-19 11:25:34,275 - INFO - ya.test.421771  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_17_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 17])
2025-11-19 11:25:34,285 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:34,286 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 29da7611-3eb8-4a86-88c4-14a48974317f, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:25:34,287 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 29da7611-3eb8-4a86-88c4-14a48974317f, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_17_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 17)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:34,297 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 29da7611-3eb8-4a86-88c4-14a48974317f, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:42,021 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:25:42,022 - DEBUG - ydb.connection.421771  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-19 11:25:42,068 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:25:42,075 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ListEndpoints, b6c4a509-bec6-45cf-99f5-fac146c83486, localhost:15506): creating call state
2025-11-19 11:25:42,076 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ListEndpoints, b6c4a509-bec6-45cf-99f5-fac146c83486, localhost:15506): request = { database: "/Root" }
2025-11-19 11:25:42,122 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ListEndpoints, b6c4a509-bec6-45cf-99f5-fac146c83486, localhost:15506): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 15506 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-19 11:25:42,123 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:15506, location 1, ssl: False>]>
2025-11-19 11:25:42,127 - DEBUG - ydb.connection.421771  - close: Closing channel for endpoint localhost:15506
2025-11-19 11:25:42,296 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:42,297 - INFO - __tests__.alter_compression.421771  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_17_compression created
2025-11-19 11:25:42,298 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:42,298 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, aba68aed-6f08-40a0-902b-c135beb5ee64, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:25:42,299 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, aba68aed-6f08-40a0-902b-c135beb5ee64, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_17_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:42,313 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, aba68aed-6f08-40a0-902b-c135beb5ee64, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:43,849 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:43,850 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:43,851 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 158c7f9b-9e55-4899-b47b-c52f5621ad19, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:25:43,852 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 158c7f9b-9e55-4899-b47b-c52f5621ad19, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:43,854 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 158c7f9b-9e55-4899-b47b-c52f5621ad19, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:51,248 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:51,249 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:51,250 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 50034fc4-e23a-4c4d-b9ab-1f4a7baed0bb, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:25:51,251 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 50034fc4-e23a-4c4d-b9ab-1f4a7baed0bb, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:51,254 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 50034fc4-e23a-4c4d-b9ab-1f4a7baed0bb, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:53,085 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:53,085 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:53,086 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 9dd1e945-6d0c-486b-976f-82e0e56de47e, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:25:53,087 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 9dd1e945-6d0c-486b-976f-82e0e56de47e, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:53,097 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 9dd1e945-6d0c-486b-976f-82e0e56de47e, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:55,544 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:55,545 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-19 11:25:55,546 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:25:55,546 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 22812f4b-c123-4972-a198-94aa1322d449, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:25:55,547 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 22812f4b-c123-4972-a198-94aa1322d449, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:55,548 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 22812f4b-c123-4972-a198-94aa1322d449, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:01,906 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:01,907 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:01,908 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, b8008400-33ac-401d-b5ee-09361ef83197, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:01,909 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, b8008400-33ac-401d-b5ee-09361ef83197, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:01,919 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, b8008400-33ac-401d-b5ee-09361ef83197, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:02,480 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:02,481 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:02,482 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 814c5bb5-0fab-4bde-8194-a54d3c60a63c, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:02,483 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 814c5bb5-0fab-4bde-8194-a54d3c60a63c, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:02,488 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 814c5bb5-0fab-4bde-8194-a54d3c60a63c, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:03,124 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:03,125 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-19 11:26:03,125 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:03,126 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 654197ca-7524-4b2f-a00a-56a8e87d4a86, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:03,126 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 654197ca-7524-4b2f-a00a-56a8e87d4a86, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:03,129 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 654197ca-7524-4b2f-a00a-56a8e87d4a86, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:08,583 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:08,583 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:08,584 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, a21af2a8-f46c-4e7e-82d5-d2a2b2d0cb84, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:08,585 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, a21af2a8-f46c-4e7e-82d5-d2a2b2d0cb84, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:08,587 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, a21af2a8-f46c-4e7e-82d5-d2a2b2d0cb84, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:09,076 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:09,077 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:09,078 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, fbf871f1-63e5-4d51-81f8-3fe40a82a639, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:09,079 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, fbf871f1-63e5-4d51-81f8-3fe40a82a639, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:09,080 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, fbf871f1-63e5-4d51-81f8-3fe40a82a639, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:09,714 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:09,715 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-19 11:26:09,715 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:09,716 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 2633930a-a909-4d5d-8060-4a9c123015b0, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:09,717 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 2633930a-a909-4d5d-8060-4a9c123015b0, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:09,719 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 2633930a-a909-4d5d-8060-4a9c123015b0, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:15,305 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:15,306 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:15,307 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, c0279193-f0ec-4862-a274-c74ce24a8442, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:15,307 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, c0279193-f0ec-4862-a274-c74ce24a8442, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:15,309 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, c0279193-f0ec-4862-a274-c74ce24a8442, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:15,722 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:15,723 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:15,723 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, b367e62a-6395-49be-bc14-0b2dd8b5418e, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:15,724 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, b367e62a-6395-49be-bc14-0b2dd8b5418e, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:15,725 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, b367e62a-6395-49be-bc14-0b2dd8b5418e, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:16,350 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:16,351 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-19 11:26:16,351 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:16,352 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 9c45bbcb-e4e6-4e06-9f1e-5d2492c9113f, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:16,353 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 9c45bbcb-e4e6-4e06-9f1e-5d2492c9113f, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:16,367 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 9c45bbcb-e4e6-4e06-9f1e-5d2492c9113f, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:21,073 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:21,074 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:21,075 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 1b7de444-8e1f-4fdc-b10a-4e129bae5eee, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:21,076 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 1b7de444-8e1f-4fdc-b10a-4e129bae5eee, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:21,077 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 1b7de444-8e1f-4fdc-b10a-4e129bae5eee, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:21,394 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:21,395 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:21,400 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 6ca98c83-783d-40d4-aeb4-ca27680a71c4, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:21,401 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 6ca98c83-783d-40d4-aeb4-ca27680a71c4, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:21,402 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 6ca98c83-783d-40d4-aeb4-ca27680a71c4, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:22,032 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:22,033 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-19 11:26:22,034 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:22,034 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 9765d95b-b16b-4cf7-8c2d-c1ff7d01b6a0, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:22,035 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 9765d95b-b16b-4cf7-8c2d-c1ff7d01b6a0, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:22,037 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 9765d95b-b16b-4cf7-8c2d-c1ff7d01b6a0, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:27,308 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:27,309 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:27,310 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, eba3b38b-aa9f-4038-bee8-fe7fcac02f7f, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:27,311 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, eba3b38b-aa9f-4038-bee8-fe7fcac02f7f, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:27,317 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, eba3b38b-aa9f-4038-bee8-fe7fcac02f7f, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:27,773 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:27,774 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:27,775 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 267fa7fc-7364-405a-960d-deed9f4818e0, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:27,776 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 267fa7fc-7364-405a-960d-deed9f4818e0, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:27,777 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 267fa7fc-7364-405a-960d-deed9f4818e0, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:28,685 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:28,686 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-19 11:26:28,687 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:28,688 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, aa820a2b-a314-4fef-a957-025d1b66fa5b, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:28,689 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, aa820a2b-a314-4fef-a957-025d1b66fa5b, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:28,690 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, aa820a2b-a314-4fef-a957-025d1b66fa5b, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:34,010 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:34,011 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:34,012 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 7617ee5f-46f0-4384-ba7a-25a019e944b8, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:34,013 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 7617ee5f-46f0-4384-ba7a-25a019e944b8, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:34,015 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 7617ee5f-46f0-4384-ba7a-25a019e944b8, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:34,385 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:34,386 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:34,387 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, f0430cb4-ae15-4361-bda9-460253055a6a, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:34,388 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, f0430cb4-ae15-4361-bda9-460253055a6a, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:34,390 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, f0430cb4-ae15-4361-bda9-460253055a6a, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:35,096 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:35,097 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-19 11:26:35,098 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:35,099 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 2a670d4d-fe4f-478e-88a1-5b72d89f47ec, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:35,100 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 2a670d4d-fe4f-478e-88a1-5b72d89f47ec, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:35,106 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 2a670d4d-fe4f-478e-88a1-5b72d89f47ec, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:42,052 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:42,053 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:42,054 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 00744cdc-10d0-4951-b63f-ca8e061c8717, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:42,055 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 00744cdc-10d0-4951-b63f-ca8e061c8717, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:42,057 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 00744cdc-10d0-4951-b63f-ca8e061c8717, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:42,537 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:42,538 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:42,539 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, afdff6ff-3855-4de6-99f5-26eb17039ee9, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:42,540 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, afdff6ff-3855-4de6-99f5-26eb17039ee9, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:42,548 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, afdff6ff-3855-4de6-99f5-26eb17039ee9, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:43,916 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:43,917 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-19 11:26:43,918 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:43,918 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 5861f686-21f9-4c7d-9adb-ba6632e45b0b, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:43,919 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 5861f686-21f9-4c7d-9adb-ba6632e45b0b, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:43,921 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 5861f686-21f9-4c7d-9adb-ba6632e45b0b, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:50,065 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:50,066 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:50,066 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 7cf4b0c6-d083-42b7-ab5a-39522ff8d30c, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:50,067 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 7cf4b0c6-d083-42b7-ab5a-39522ff8d30c, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:50,069 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 7cf4b0c6-d083-42b7-ab5a-39522ff8d30c, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:50,584 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:50,585 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:50,585 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 96e6e718-f56b-474b-aa6e-9499cb418a57, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:50,586 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 96e6e718-f56b-474b-aa6e-9499cb418a57, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:50,590 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 96e6e718-f56b-474b-aa6e-9499cb418a57, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:51,759 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:51,760 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-19 11:26:51,761 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:51,762 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, bad9a0e5-c846-457e-ac6f-87891690fbb6, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:51,763 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, bad9a0e5-c846-457e-ac6f-87891690fbb6, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:51,766 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, bad9a0e5-c846-457e-ac6f-87891690fbb6, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:58,932 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:58,933 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:58,934 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 720da1d4-71f5-4d2e-a064-367fc1caea35, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:58,934 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 720da1d4-71f5-4d2e-a064-367fc1caea35, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:58,938 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 720da1d4-71f5-4d2e-a064-367fc1caea35, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:59,384 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:59,385 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:26:59,386 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 50740648-7cf1-4bc4-98a2-d0c651d4cb66, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:26:59,387 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 50740648-7cf1-4bc4-98a2-d0c651d4cb66, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:59,389 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 50740648-7cf1-4bc4-98a2-d0c651d4cb66, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:27:00,536 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:00,536 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-19 11:27:00,537 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:00,538 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 6d0b622c-2022-4301-b4bd-2c60aa39e7c0, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:27:00,539 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 6d0b622c-2022-4301-b4bd-2c60aa39e7c0, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_17_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:27:00,540 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 6d0b622c-2022-4301-b4bd-2c60aa39e7c0, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:27:03,008 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:03,009 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:03,009 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 5c4c9158-31e6-4ddf-b928-40ba301049af, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:27:03,010 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 5c4c9158-31e6-4ddf-b928-40ba301049af, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:27:03,021 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 5c4c9158-31e6-4ddf-b928-40ba301049af, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:27:03,501 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:03,502 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:03,503 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 253752be-98a5-49f1-88f6-df77cafd38d8, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:27:03,503 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 253752be-98a5-49f1-88f6-df77cafd38d8, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:27:03,505 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 253752be-98a5-49f1-88f6-df77cafd38d8, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:27:03,988 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:03,988 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:03,989 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 11cc096e-b2c2-4755-b938-e02b4b1e366d, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:27:03,990 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 11cc096e-b2c2-4755-b938-e02b4b1e366d, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:27:03,996 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 11cc096e-b2c2-4755-b938-e02b4b1e366d, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:27:11,003 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:21,103 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:21,104 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 0501d8ed-0b58-48a4-b0d3-1187dedbaa6c, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:27:21,105 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 0501d8ed-0b58-48a4-b0d3-1187dedbaa6c, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:27:21,107 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 0501d8ed-0b58-48a4-b0d3-1187dedbaa6c, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:27:25,137 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:25,183 - INFO - root.421771  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_17_compression`, volumes `value` (8000000, 1595704)
2025-11-19 11:27:25,183 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:25,184 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 7ca27772-cfb2-4caa-967d-e04ef491cf92, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:27:25,185 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 7ca27772-cfb2-4caa-967d-e04ef491cf92, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:27:25,186 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 7ca27772-cfb2-4caa-967d-e04ef491cf92, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:27:25,629 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:25,629 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:25,630 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, aee91d25-3dad-4574-a512-17739b6e0fb3, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:27:25,631 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, aee91d25-3dad-4574-a512-17739b6e0fb3, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:27:25,633 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, aee91d25-3dad-4574-a512-17739b6e0fb3, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:27:28,692 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:27:28,693 - DEBUG - ydb.connection.421771  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-19 11:27:28,737 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:27:28,738 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ListEndpoints, cf84e0c3-0738-4fef-aa25-486f3ffc2485, localhost:15506): creating call state
2025-11-19 11:27:28,738 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ListEndpoints, cf84e0c3-0738-4fef-aa25-486f3ffc2485, localhost:15506): request = { database: "/Root" }
2025-11-19 11:27:28,762 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ListEndpoints, cf84e0c3-0738-4fef-aa25-486f3ffc2485, localhost:15506): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 15506 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-19 11:27:28,763 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:15506, location 1, ssl: False>]>
2025-11-19 11:27:28,765 - DEBUG - ydb.connection.421771  - close: Closing channel for endpoint localhost:15506
2025-11-19 11:27:31,112 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:41,200 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:41,201 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 4028927a-407a-4e3a-bfaa-1627880f07e0, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:27:41,202 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 4028927a-407a-4e3a-bfaa-1627880f07e0, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:27:41,203 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 4028927a-407a-4e3a-bfaa-1627880f07e0, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:27:45,076 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:55,112 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:27:55,113 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 75ec264a-eca3-47dd-8eea-11abd93feccf, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:27:55,114 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 75ec264a-eca3-47dd-8eea-11abd93feccf, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:27:55,115 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 75ec264a-eca3-47dd-8eea-11abd93feccf, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:27:57,382 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:28:07,408 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:28:07,409 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 1af90199-545d-4f14-b48a-725c3b6637a1, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:28:07,410 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 1af90199-545d-4f14-b48a-725c3b6637a1, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:28:07,411 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 1af90199-545d-4f14-b48a-725c3b6637a1, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:28:08,240 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:28:08,244 - INFO - root.421771  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_17_compression`, volumes `value` (8000000, 1497984)
2025-11-19 11:28:08,245 - INFO - root.421771  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_17_compression` 8092160 / 1497984: 5.402033666581218
2025-11-19 11:28:08,246 - INFO - ya.test.421771  - pytest_runtest_teardown: Test teardown
2025-11-19 11:28:08,247 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(DeleteSession, 8db7c588-a567-4974-bc06-4030894b9f7c, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:28:08,248 - DEBUG - ydb.connection.421771  - _log_request: RpcState(DeleteSession, 8db7c588-a567-4974-bc06-4030894b9f7c, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" }
2025-11-19 11:28:08,253 - DEBUG - ydb.connection.421771  - _log_response: RpcState(DeleteSession, 8db7c588-a567-4974-bc06-4030894b9f7c, ghrun-alsqrntpxi.auto.internal:15506): response = { status: SUCCESS }
2025-11-19 11:28:08,254 - DEBUG - ydb.query.pool.421771  - stop: All session were deleted.
2025-11-19 11:28:08,254 - DEBUG - ydb.topic.421771  - close: Close topic client
2025-11-19 11:28:08,254 - DEBUG - ydb.topic.421771  - close: Topic client was closed
2025-11-19 11:28:08,255 - DEBUG - ydb.connection.421771  - close: Closing channel for endpoint localhost:15506
2025-11-19 11:28:08,256 - DEBUG - ydb.connection.421771  - close: Closing channel for endpoint ghrun-alsqrntpxi.auto.internal:15506
2025-11-19 11:28:08,264 - DEBUG - ydb.connection.421771  - _rpc_error_handler: RpcState(AttachSession, fb1bd06e-e8b1-4e6d-8a35-b625b938aa4b, ghrun-alsqrntpxi.auto.internal:15506): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-19 11:28:08,270 - INFO - ydb.pool.Discovery.421771  - run: Successfully terminated discovery process
2025-11-19 11:28:20,288 - INFO - ydb.tests.library.harness.kikimr_runner.421771  - stop: Stopped node localhost:15506/1
2025-11-19 11:28:20,292 - DEBUG - library.python.filelock.421771  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21803
2025-11-19 11:28:20,293 - DEBUG - library.python.filelock.421771  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/62832
2025-11-19 11:28:20,293 - DEBUG - library.python.filelock.421771  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31552
2025-11-19 11:28:20,293 - DEBUG - library.python.filelock.421771  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15506
2025-11-19 11:28:20,298 - DEBUG - library.python.filelock.421771  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63376
