2025-11-21 20:48:22,630 - INFO - ya.test.678902  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:48:22,631 - INFO - ya.test.678902  - pytest_runtest_setup: test_all_supported_compression[zstd_17_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 17]
2025-11-21 20:48:22,632 - INFO - ya.test.678902  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:48:22,632 - INFO - ya.test.678902  - pytest_runtest_setup: Test setup
2025-11-21 20:48:22,636 - INFO - ya.test.678902  - 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-21 20:48:22,637 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:22,638 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 4ba49d8d-d702-47a1-b5de-78c7d12f924a, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:22,639 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 4ba49d8d-d702-47a1-b5de-78c7d12f924a, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:22,657 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 4ba49d8d-d702-47a1-b5de-78c7d12f924a, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:27,696 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:27,696 - INFO - __tests__.alter_compression.678902  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_17_compression created
2025-11-21 20:48:27,697 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:27,698 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 9a57fbb3-dd9e-4bf2-8385-f899d485636f, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:27,699 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 9a57fbb3-dd9e-4bf2-8385-f899d485636f, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:27,701 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 9a57fbb3-dd9e-4bf2-8385-f899d485636f, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:29,122 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:29,123 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:29,123 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 395ff2b5-10e2-4ca8-abb1-09a5dcbf9951, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:29,125 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 395ff2b5-10e2-4ca8-abb1-09a5dcbf9951, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:29,126 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 395ff2b5-10e2-4ca8-abb1-09a5dcbf9951, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:36,512 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:36,513 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:36,514 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 0be3549f-cc80-4471-b452-438d0e624b2a, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:36,515 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 0be3549f-cc80-4471-b452-438d0e624b2a, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:36,517 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 0be3549f-cc80-4471-b452-438d0e624b2a, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:38,013 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:38,014 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:38,015 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 5efce1f1-2896-40b8-a356-5f65c0c896c5, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:38,016 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 5efce1f1-2896-40b8-a356-5f65c0c896c5, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:38,027 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 5efce1f1-2896-40b8-a356-5f65c0c896c5, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:40,976 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:40,977 - INFO - __tests__.alter_compression.678902  - 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-21 20:48:40,977 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:40,978 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, fe0f2817-d978-4f10-bd4c-fd64a766f53b, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:40,980 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, fe0f2817-d978-4f10-bd4c-fd64a766f53b, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:40,981 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, fe0f2817-d978-4f10-bd4c-fd64a766f53b, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:48,606 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:48,607 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:48,608 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 5c484a00-6a21-4374-95ef-2e8dcc1b2682, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:48,609 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 5c484a00-6a21-4374-95ef-2e8dcc1b2682, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:48,611 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 5c484a00-6a21-4374-95ef-2e8dcc1b2682, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:49,032 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:49,033 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:49,034 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 3029d61e-03ae-4eeb-bc22-60995c1776ea, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:49,035 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 3029d61e-03ae-4eeb-bc22-60995c1776ea, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:49,036 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 3029d61e-03ae-4eeb-bc22-60995c1776ea, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:49,787 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:49,788 - INFO - __tests__.alter_compression.678902  - 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-21 20:48:49,789 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:49,790 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, bca1d8ed-80a1-4e6b-b113-a45f71048308, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:49,792 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, bca1d8ed-80a1-4e6b-b113-a45f71048308, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:49,794 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, bca1d8ed-80a1-4e6b-b113-a45f71048308, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:55,821 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:55,822 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:55,824 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 475809f1-4e31-4909-9032-44e303a03954, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:55,825 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 475809f1-4e31-4909-9032-44e303a03954, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:55,828 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 475809f1-4e31-4909-9032-44e303a03954, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:56,268 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:56,269 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:56,270 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, cc8e7c5e-0a58-4172-9480-e78499ab86d7, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:56,271 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, cc8e7c5e-0a58-4172-9480-e78499ab86d7, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:56,282 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, cc8e7c5e-0a58-4172-9480-e78499ab86d7, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:57,109 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:57,110 - INFO - __tests__.alter_compression.678902  - 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-21 20:48:57,110 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:57,111 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, cea5f403-e16b-4cb4-a181-d1fe2f5d1dc1, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:57,112 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, cea5f403-e16b-4cb4-a181-d1fe2f5d1dc1, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:48:57,114 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, cea5f403-e16b-4cb4-a181-d1fe2f5d1dc1, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:00,063 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.678902  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 20:49:00,064 - DEBUG - ydb.connection.678902  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 20:49:00,090 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.678902  - context_resolve: Resolving endpoints for database /Root
2025-11-21 20:49:00,091 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ListEndpoints, 4720b3ef-c1a7-444a-8d0a-c8102fbac48a, localhost:14957): creating call state
2025-11-21 20:49:00,092 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ListEndpoints, 4720b3ef-c1a7-444a-8d0a-c8102fbac48a, localhost:14957): request = { database: "/Root" }
2025-11-21 20:49:00,100 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ListEndpoints, 4720b3ef-c1a7-444a-8d0a-c8102fbac48a, localhost:14957): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-frzglb4yoy.auto.internal" port: 14957 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-21 20:49:00,101 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.678902  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-frzglb4yoy.auto.internal:14957, location 1, ssl: False>]>
2025-11-21 20:49:00,101 - DEBUG - ydb.connection.678902  - close: Closing channel for endpoint localhost:14957
2025-11-21 20:49:03,402 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:03,403 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:03,404 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 77e993b0-3b4d-4e8b-b1ed-ad09fba2ce8d, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:03,404 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 77e993b0-3b4d-4e8b-b1ed-ad09fba2ce8d, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:03,406 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 77e993b0-3b4d-4e8b-b1ed-ad09fba2ce8d, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:03,768 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:03,769 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:03,769 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 00cb6c67-8475-43cb-ae6e-c41d7dc9453d, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:03,770 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 00cb6c67-8475-43cb-ae6e-c41d7dc9453d, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:03,772 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 00cb6c67-8475-43cb-ae6e-c41d7dc9453d, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:04,546 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:04,547 - INFO - __tests__.alter_compression.678902  - 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-21 20:49:04,547 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:04,548 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, ea2829b6-3e17-4972-ad4f-ce150c8d307a, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:04,549 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, ea2829b6-3e17-4972-ad4f-ce150c8d307a, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:04,550 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, ea2829b6-3e17-4972-ad4f-ce150c8d307a, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:10,357 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:10,358 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:10,358 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 6ddf3294-179f-436c-aafc-d85c810b904d, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:10,359 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 6ddf3294-179f-436c-aafc-d85c810b904d, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:10,361 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 6ddf3294-179f-436c-aafc-d85c810b904d, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:10,664 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:10,664 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:10,665 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 0ac3bb9b-17a1-4383-959a-4eae24cf18e1, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:10,666 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 0ac3bb9b-17a1-4383-959a-4eae24cf18e1, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:10,668 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 0ac3bb9b-17a1-4383-959a-4eae24cf18e1, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:11,283 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:11,284 - INFO - __tests__.alter_compression.678902  - 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-21 20:49:11,284 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:11,285 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 5941e962-1731-4235-8e31-7ade8956df91, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:11,286 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 5941e962-1731-4235-8e31-7ade8956df91, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:11,288 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 5941e962-1731-4235-8e31-7ade8956df91, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:16,839 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:16,840 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:16,840 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 52696b85-2f11-4bde-b030-098347781f31, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:16,841 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 52696b85-2f11-4bde-b030-098347781f31, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:16,842 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 52696b85-2f11-4bde-b030-098347781f31, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:17,499 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:17,500 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:17,501 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 50a9b984-7e1f-406e-a2c7-be43d6aa5aad, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:17,501 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 50a9b984-7e1f-406e-a2c7-be43d6aa5aad, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:17,510 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 50a9b984-7e1f-406e-a2c7-be43d6aa5aad, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:18,179 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:18,180 - INFO - __tests__.alter_compression.678902  - 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-21 20:49:18,183 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:18,184 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 09fd8d97-a9f4-42ad-947f-607ec8abb0bd, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:18,185 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 09fd8d97-a9f4-42ad-947f-607ec8abb0bd, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:18,187 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 09fd8d97-a9f4-42ad-947f-607ec8abb0bd, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:22,747 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:22,748 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:22,748 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 7da9435d-c3c9-41fb-a059-fe557d53da87, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:22,749 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 7da9435d-c3c9-41fb-a059-fe557d53da87, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:22,751 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 7da9435d-c3c9-41fb-a059-fe557d53da87, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:23,300 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:23,301 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:23,302 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, b5389ba2-5027-4a3b-84ea-f0dc6e4ff4e9, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:23,303 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, b5389ba2-5027-4a3b-84ea-f0dc6e4ff4e9, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:23,305 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, b5389ba2-5027-4a3b-84ea-f0dc6e4ff4e9, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:24,247 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:24,248 - INFO - __tests__.alter_compression.678902  - 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-21 20:49:24,249 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:24,249 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, cbac11be-8d50-481d-845f-1adc954c6938, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:24,250 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, cbac11be-8d50-481d-845f-1adc954c6938, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:24,252 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, cbac11be-8d50-481d-845f-1adc954c6938, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:29,843 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:29,844 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:29,845 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 7bb56870-83cd-44ac-a0a0-5a8da7c10aee, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:29,846 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 7bb56870-83cd-44ac-a0a0-5a8da7c10aee, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:29,855 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 7bb56870-83cd-44ac-a0a0-5a8da7c10aee, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:30,375 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:30,376 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:30,377 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 1ba61fec-9b14-43c2-91cb-377272c6d1e6, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:30,378 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 1ba61fec-9b14-43c2-91cb-377272c6d1e6, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:30,385 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 1ba61fec-9b14-43c2-91cb-377272c6d1e6, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:31,268 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:31,269 - INFO - __tests__.alter_compression.678902  - 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-21 20:49:31,270 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:31,270 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 88579480-8b32-43da-972f-4f53a638ed1d, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:31,272 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 88579480-8b32-43da-972f-4f53a638ed1d, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:31,340 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 88579480-8b32-43da-972f-4f53a638ed1d, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:37,093 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:37,094 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:37,094 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 7712861b-50ea-4a46-8f81-5b23cb3cd7e9, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:37,095 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 7712861b-50ea-4a46-8f81-5b23cb3cd7e9, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:37,098 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 7712861b-50ea-4a46-8f81-5b23cb3cd7e9, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:37,479 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:37,480 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:37,481 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 11798b3a-f2f4-4705-9176-6624f93ff4bb, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:37,482 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 11798b3a-f2f4-4705-9176-6624f93ff4bb, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:37,488 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 11798b3a-f2f4-4705-9176-6624f93ff4bb, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:38,329 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:38,330 - INFO - __tests__.alter_compression.678902  - 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-21 20:49:38,331 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:38,332 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, f9245cd0-f671-4f68-82b3-c826079202e7, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:38,333 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, f9245cd0-f671-4f68-82b3-c826079202e7, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:38,334 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, f9245cd0-f671-4f68-82b3-c826079202e7, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:44,159 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:44,159 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:44,160 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 4cd1ef9f-4f6f-4cad-9474-c0dfe481221f, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:44,161 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 4cd1ef9f-4f6f-4cad-9474-c0dfe481221f, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:44,164 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 4cd1ef9f-4f6f-4cad-9474-c0dfe481221f, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:44,527 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:44,528 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:44,529 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, ee5e5541-2a57-42d8-b734-18b252f5b3d4, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:44,530 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, ee5e5541-2a57-42d8-b734-18b252f5b3d4, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:44,536 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, ee5e5541-2a57-42d8-b734-18b252f5b3d4, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:46,160 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:46,160 - INFO - __tests__.alter_compression.678902  - 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-21 20:49:46,161 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:46,161 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 7f5c4eba-9794-4c46-9e7c-40a09deda8d5, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:46,162 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 7f5c4eba-9794-4c46-9e7c-40a09deda8d5, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:46,164 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 7f5c4eba-9794-4c46-9e7c-40a09deda8d5, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:47,900 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:47,901 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:47,902 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, d42a872d-6526-4c86-8f96-8128cab8448d, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:47,903 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, d42a872d-6526-4c86-8f96-8128cab8448d, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:47,906 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, d42a872d-6526-4c86-8f96-8128cab8448d, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:48,332 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:48,333 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:48,333 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 7c1a649d-5371-4bbb-8604-c7e4301d2437, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:48,334 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 7c1a649d-5371-4bbb-8604-c7e4301d2437, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:48,340 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 7c1a649d-5371-4bbb-8604-c7e4301d2437, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:48,868 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:48,869 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:49:48,870 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, a33f681f-ce25-445e-9b6e-aae3b0509d3e, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:49:48,871 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, a33f681f-ce25-445e-9b6e-aae3b0509d3e, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:49:48,872 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, a33f681f-ce25-445e-9b6e-aae3b0509d3e, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:54,182 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:50:04,231 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:50:04,232 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 25e10e22-caa4-4191-910d-24070bf0d71b, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:50:04,232 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 25e10e22-caa4-4191-910d-24070bf0d71b, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:50:04,234 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 25e10e22-caa4-4191-910d-24070bf0d71b, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:50:07,936 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:50:07,974 - INFO - root.678902  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_17_compression`, volumes `value` (8000000, 1595704)
2025-11-21 20:50:07,975 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:50:07,976 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 75280534-9d11-4e54-8967-709457635566, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:50:07,977 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 75280534-9d11-4e54-8967-709457635566, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:50:07,978 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 75280534-9d11-4e54-8967-709457635566, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:50:08,278 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:50:08,279 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:50:08,280 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 2005d9ab-a5fc-4c28-b939-e9e3fca7a9d3, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:50:08,281 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 2005d9ab-a5fc-4c28-b939-e9e3fca7a9d3, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:50:08,282 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 2005d9ab-a5fc-4c28-b939-e9e3fca7a9d3, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:50:12,004 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:50:22,043 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:50:22,044 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 7909adce-216b-4f8c-80f0-bcd82a81da9d, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:50:22,045 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 7909adce-216b-4f8c-80f0-bcd82a81da9d, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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-21 20:50:22,048 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 7909adce-216b-4f8c-80f0-bcd82a81da9d, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:50:25,773 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:50:25,819 - INFO - root.678902  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_17_compression`, volumes `value` (8000000, 1595704)
2025-11-21 20:50:25,820 - INFO - root.678902  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_17_compression` 8092160 / 1595704: 5.071216215538722
2025-11-21 20:50:25,822 - INFO - ya.test.678902  - pytest_runtest_teardown: Test teardown
2025-11-21 20:50:25,824 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(DeleteSession, 277a4e18-042a-4f53-b0af-f3c9c1aed54f, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:50:25,825 - DEBUG - ydb.connection.678902  - _log_request: RpcState(DeleteSession, 277a4e18-042a-4f53-b0af-f3c9c1aed54f, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" }
2025-11-21 20:50:25,830 - DEBUG - ydb.connection.678902  - _log_response: RpcState(DeleteSession, 277a4e18-042a-4f53-b0af-f3c9c1aed54f, ghrun-frzglb4yoy.auto.internal:14957): response = { status: SUCCESS }
2025-11-21 20:50:25,832 - DEBUG - ydb.query.pool.678902  - stop: All session were deleted.
2025-11-21 20:50:25,832 - DEBUG - ydb.topic.678902  - close: Close topic client
2025-11-21 20:50:25,833 - DEBUG - ydb.topic.678902  - close: Topic client was closed
2025-11-21 20:50:25,833 - DEBUG - ydb.connection.678902  - close: Closing channel for endpoint localhost:14957
2025-11-21 20:50:25,834 - DEBUG - ydb.connection.678902  - close: Closing channel for endpoint ghrun-frzglb4yoy.auto.internal:14957
2025-11-21 20:50:25,841 - DEBUG - ydb.connection.678902  - _rpc_error_handler: RpcState(AttachSession, f1ab8a11-457e-4466-a994-9ef9ea89b224, ghrun-frzglb4yoy.auto.internal:14957): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-21 20:50:25,843 - INFO - ydb.pool.Discovery.678902  - run: Successfully terminated discovery process
2025-11-21 20:50:42,867 - INFO - ydb.tests.library.harness.kikimr_runner.678902  - stop: Stopped node localhost:14957/1
2025-11-21 20:50:42,871 - DEBUG - library.python.filelock.678902  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27488
2025-11-21 20:50:42,879 - DEBUG - library.python.filelock.678902  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64718
2025-11-21 20:50:42,879 - DEBUG - library.python.filelock.678902  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22928
2025-11-21 20:50:42,880 - DEBUG - library.python.filelock.678902  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14957
2025-11-21 20:50:42,880 - DEBUG - library.python.filelock.678902  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23466
