2025-11-21 14:10:24,157 - INFO - ya.test.1140674  - pytest_runtest_setup: ####################################################################################################
2025-11-21 14:10:24,157 - INFO - ya.test.1140674  - pytest_runtest_setup: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16]
2025-11-21 14:10:24,157 - INFO - ya.test.1140674  - pytest_runtest_setup: ####################################################################################################
2025-11-21 14:10:24,158 - INFO - ya.test.1140674  - pytest_runtest_setup: Test setup
2025-11-21 14:10:24,160 - INFO - ya.test.1140674  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16])
2025-11-21 14:10:24,161 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:24,161 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 21836683-6e2a-4d11-8b9d-2dbe04f21bd3, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:10:24,162 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 21836683-6e2a-4d11-8b9d-2dbe04f21bd3, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_16_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 16)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:24,172 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 21836683-6e2a-4d11-8b9d-2dbe04f21bd3, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:32,391 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:32,392 - INFO - __tests__.alter_compression.1140674  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_16_compression created
2025-11-21 14:10:32,392 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:32,393 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 899e2260-f3cb-48f9-92de-b838bd585495, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:10:32,394 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 899e2260-f3cb-48f9-92de-b838bd585495, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:32,397 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 899e2260-f3cb-48f9-92de-b838bd585495, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:34,434 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:34,435 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:34,436 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 99a981e4-6a63-4ec1-a25b-eb3e011ce52c, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:10:34,437 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 99a981e4-6a63-4ec1-a25b-eb3e011ce52c, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:34,438 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 99a981e4-6a63-4ec1-a25b-eb3e011ce52c, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:40,344 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:40,345 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:40,346 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 7e7f5086-8947-4d70-bb40-9bc02a77a79c, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:10:40,347 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 7e7f5086-8947-4d70-bb40-9bc02a77a79c, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:40,348 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 7e7f5086-8947-4d70-bb40-9bc02a77a79c, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:41,699 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:41,700 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:41,701 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 36c86996-5e39-4f26-9e8c-9fbcf9ef062c, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:10:41,702 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 36c86996-5e39-4f26-9e8c-9fbcf9ef062c, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:41,703 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 36c86996-5e39-4f26-9e8c-9fbcf9ef062c, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:44,384 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:44,385 - INFO - __tests__.alter_compression.1140674  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-21 14:10:44,385 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:44,386 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, c8d6a7bf-be5b-4b3e-a91e-e4acc054444a, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:10:44,387 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, c8d6a7bf-be5b-4b3e-a91e-e4acc054444a, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:44,388 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, c8d6a7bf-be5b-4b3e-a91e-e4acc054444a, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:51,766 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:51,767 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:51,769 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 1407fbe4-f909-4458-a484-05aa7ba3addb, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:10:51,770 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 1407fbe4-f909-4458-a484-05aa7ba3addb, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:51,774 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 1407fbe4-f909-4458-a484-05aa7ba3addb, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:52,406 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:52,407 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:52,408 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, fc217b65-e198-44e6-9c26-3fa12f569faf, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:10:52,409 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, fc217b65-e198-44e6-9c26-3fa12f569faf, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:52,416 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, fc217b65-e198-44e6-9c26-3fa12f569faf, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:53,622 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:53,622 - INFO - __tests__.alter_compression.1140674  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-21 14:10:53,623 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:10:53,624 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 577f3a44-09ab-4a57-a631-5fc8a2eed79e, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:10:53,625 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 577f3a44-09ab-4a57-a631-5fc8a2eed79e, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:53,639 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 577f3a44-09ab-4a57-a631-5fc8a2eed79e, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:03,163 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:03,164 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:03,165 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 9572421f-b2c5-49ea-94ca-81f3a152aba5, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:03,166 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 9572421f-b2c5-49ea-94ca-81f3a152aba5, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:03,183 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 9572421f-b2c5-49ea-94ca-81f3a152aba5, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:04,199 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:04,200 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:04,201 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 05753d2e-b40c-4070-89c9-3f2f9efcc825, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:04,202 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 05753d2e-b40c-4070-89c9-3f2f9efcc825, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:04,204 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 05753d2e-b40c-4070-89c9-3f2f9efcc825, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:05,149 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:05,150 - INFO - __tests__.alter_compression.1140674  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-21 14:11:05,150 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:05,151 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 198224f4-c310-46d7-8f5c-e4821c3df23a, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:05,152 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 198224f4-c310-46d7-8f5c-e4821c3df23a, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:05,159 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 198224f4-c310-46d7-8f5c-e4821c3df23a, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:16,394 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:16,395 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:16,400 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 13cbe18b-4adc-4e9f-9288-476bb437fd05, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:16,410 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 13cbe18b-4adc-4e9f-9288-476bb437fd05, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:16,412 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 13cbe18b-4adc-4e9f-9288-476bb437fd05, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:16,912 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:16,914 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:16,915 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 38d69cb4-3166-448b-bd4b-72ccca3fa83b, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:16,916 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 38d69cb4-3166-448b-bd4b-72ccca3fa83b, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:16,932 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 38d69cb4-3166-448b-bd4b-72ccca3fa83b, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:18,910 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:18,911 - INFO - __tests__.alter_compression.1140674  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-21 14:11:18,912 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:18,912 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 4eac3884-3b58-4f0d-bd29-6603f7c9f080, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:18,913 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 4eac3884-3b58-4f0d-bd29-6603f7c9f080, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:18,924 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 4eac3884-3b58-4f0d-bd29-6603f7c9f080, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:30,780 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:30,781 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:30,782 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, f6060671-7efd-49c5-997b-df0c174e5d1d, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:30,783 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, f6060671-7efd-49c5-997b-df0c174e5d1d, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:30,787 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, f6060671-7efd-49c5-997b-df0c174e5d1d, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:32,739 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:32,740 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:32,741 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, b337eaa5-0c8f-4ad7-9eaa-c8ed67c7b0c9, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:32,742 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, b337eaa5-0c8f-4ad7-9eaa-c8ed67c7b0c9, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:32,751 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, b337eaa5-0c8f-4ad7-9eaa-c8ed67c7b0c9, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:33,728 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:33,729 - INFO - __tests__.alter_compression.1140674  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-21 14:11:33,729 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:33,730 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, b2076b66-c95d-4831-8b9a-cc79b968297f, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:33,730 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, b2076b66-c95d-4831-8b9a-cc79b968297f, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:33,732 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, b2076b66-c95d-4831-8b9a-cc79b968297f, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:38,004 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1140674  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 14:11:38,004 - DEBUG - ydb.connection.1140674  - 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 14:11:38,062 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1140674  - context_resolve: Resolving endpoints for database /Root
2025-11-21 14:11:38,063 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ListEndpoints, 08e067f9-845f-473e-be9a-bf6f66de814d, localhost:63750): creating call state
2025-11-21 14:11:38,064 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ListEndpoints, 08e067f9-845f-473e-be9a-bf6f66de814d, localhost:63750): request = { database: "/Root" }
2025-11-21 14:11:38,077 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ListEndpoints, 08e067f9-845f-473e-be9a-bf6f66de814d, localhost:63750): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-acwccf6kvy.auto.internal" port: 63750 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 14:11:38,078 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1140674  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-acwccf6kvy.auto.internal:63750, location 1, ssl: False>]>
2025-11-21 14:11:38,078 - DEBUG - ydb.connection.1140674  - close: Closing channel for endpoint localhost:63750
2025-11-21 14:11:41,994 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:41,995 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:41,996 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 8a63e7f3-0cc5-4e73-92a6-c3e4bead6740, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:41,997 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 8a63e7f3-0cc5-4e73-92a6-c3e4bead6740, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:41,998 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 8a63e7f3-0cc5-4e73-92a6-c3e4bead6740, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:42,767 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:42,768 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:42,769 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 8f6a695a-935b-42b4-92d1-63f37bea28eb, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:42,770 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 8f6a695a-935b-42b4-92d1-63f37bea28eb, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:42,776 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 8f6a695a-935b-42b4-92d1-63f37bea28eb, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:44,468 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:44,469 - INFO - __tests__.alter_compression.1140674  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-21 14:11:44,469 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:44,470 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 5823bea4-b815-4e8c-9bc6-0b233b41da0b, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:44,471 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 5823bea4-b815-4e8c-9bc6-0b233b41da0b, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:44,473 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 5823bea4-b815-4e8c-9bc6-0b233b41da0b, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:52,391 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:52,392 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:52,393 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, edd9ed39-1a07-4666-b33a-a73e76c83a68, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:52,394 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, edd9ed39-1a07-4666-b33a-a73e76c83a68, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:52,400 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, edd9ed39-1a07-4666-b33a-a73e76c83a68, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:52,896 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:52,897 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:52,898 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, e1a35712-425e-4053-87d4-6242e26b3446, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:52,917 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, e1a35712-425e-4053-87d4-6242e26b3446, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:52,919 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, e1a35712-425e-4053-87d4-6242e26b3446, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:54,184 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:54,192 - INFO - __tests__.alter_compression.1140674  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-21 14:11:54,193 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:11:54,194 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, b97aae24-376d-44ff-9f1e-b9eec3c38507, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:11:54,196 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, b97aae24-376d-44ff-9f1e-b9eec3c38507, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:11:54,198 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, b97aae24-376d-44ff-9f1e-b9eec3c38507, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:00,087 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:00,088 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:00,089 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 25adb8d6-88b5-4cc8-afd2-faf4c8546fef, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:00,090 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 25adb8d6-88b5-4cc8-afd2-faf4c8546fef, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:00,096 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 25adb8d6-88b5-4cc8-afd2-faf4c8546fef, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:00,440 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:00,441 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:00,442 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 6c562098-370d-483e-b2d8-f84dd2829190, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:00,443 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 6c562098-370d-483e-b2d8-f84dd2829190, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:00,445 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 6c562098-370d-483e-b2d8-f84dd2829190, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:01,042 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:01,042 - INFO - __tests__.alter_compression.1140674  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-21 14:12:01,043 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:01,044 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 5d5f4a85-66d2-4a90-968e-ca907a739df1, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:01,045 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 5d5f4a85-66d2-4a90-968e-ca907a739df1, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:01,057 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 5d5f4a85-66d2-4a90-968e-ca907a739df1, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:09,364 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:09,365 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:09,366 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 62b1fb39-4c45-4e2a-9084-03a2f84be2a0, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:09,367 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 62b1fb39-4c45-4e2a-9084-03a2f84be2a0, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:09,375 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 62b1fb39-4c45-4e2a-9084-03a2f84be2a0, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:10,121 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:10,121 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:10,122 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 289341e4-9224-4083-9856-9e5268c1ec03, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:10,123 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 289341e4-9224-4083-9856-9e5268c1ec03, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:10,124 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 289341e4-9224-4083-9856-9e5268c1ec03, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:11,736 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:11,737 - INFO - __tests__.alter_compression.1140674  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-21 14:12:11,737 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:11,738 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, d6cb43e2-3a2f-4cd3-8bc1-a22d3dc4bb1f, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:11,739 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, d6cb43e2-3a2f-4cd3-8bc1-a22d3dc4bb1f, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:11,740 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, d6cb43e2-3a2f-4cd3-8bc1-a22d3dc4bb1f, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:18,327 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:18,328 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:18,328 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 0f382f63-c15e-4fe3-bc27-674c78737684, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:18,329 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 0f382f63-c15e-4fe3-bc27-674c78737684, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:18,330 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 0f382f63-c15e-4fe3-bc27-674c78737684, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:19,095 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:19,096 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:19,097 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 3decbb74-547b-42a4-bc7e-59e9ccde172b, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:19,097 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 3decbb74-547b-42a4-bc7e-59e9ccde172b, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:19,099 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 3decbb74-547b-42a4-bc7e-59e9ccde172b, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:20,795 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:20,795 - INFO - __tests__.alter_compression.1140674  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-21 14:12:20,796 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:20,797 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, ad4d6ccd-7555-415e-87f4-5ae40b8d1c29, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:20,797 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, ad4d6ccd-7555-415e-87f4-5ae40b8d1c29, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:20,815 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, ad4d6ccd-7555-415e-87f4-5ae40b8d1c29, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:23,318 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:23,319 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:23,319 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 575a6285-3631-47b6-86f4-59472c45ecb1, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:23,320 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 575a6285-3631-47b6-86f4-59472c45ecb1, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:23,323 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 575a6285-3631-47b6-86f4-59472c45ecb1, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:23,904 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:23,905 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:23,905 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 50f6177a-e0af-47ee-a9f6-d2cc0e6da0df, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:23,906 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 50f6177a-e0af-47ee-a9f6-d2cc0e6da0df, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:23,920 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 50f6177a-e0af-47ee-a9f6-d2cc0e6da0df, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:24,590 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:24,591 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:24,592 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 35475849-d8fa-4077-b93a-6874d4dc7373, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:24,593 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 35475849-d8fa-4077-b93a-6874d4dc7373, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:24,600 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 35475849-d8fa-4077-b93a-6874d4dc7373, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:30,020 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:40,071 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:40,072 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, b5d857ec-78b3-40f5-b2c1-03532131aeda, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:40,073 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, b5d857ec-78b3-40f5-b2c1-03532131aeda, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:40,074 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, b5d857ec-78b3-40f5-b2c1-03532131aeda, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:44,272 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:54,306 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:12:54,307 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 2fd320c5-f5f0-4a41-b1e4-3059b9651d11, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:12:54,307 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 2fd320c5-f5f0-4a41-b1e4-3059b9651d11, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:12:54,309 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 2fd320c5-f5f0-4a41-b1e4-3059b9651d11, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:57,062 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:13:07,079 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:13:07,080 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 538e16d8-ac71-4180-96bd-51b22c472f0f, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:13:07,081 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 538e16d8-ac71-4180-96bd-51b22c472f0f, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:13:07,082 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 538e16d8-ac71-4180-96bd-51b22c472f0f, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:13:08,357 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:13:08,362 - INFO - root.1140674  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1497984)
2025-11-21 14:13:08,362 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:13:08,364 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 30923ee7-adab-4d59-a9d3-05c208e4f3eb, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:13:08,365 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 30923ee7-adab-4d59-a9d3-05c208e4f3eb, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:13:08,366 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 30923ee7-adab-4d59-a9d3-05c208e4f3eb, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:13:09,270 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:13:09,271 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:13:09,272 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 77987d11-db65-48a1-b9c5-a1a1112ea0d9, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:13:09,273 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 77987d11-db65-48a1-b9c5-a1a1112ea0d9, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:13:09,274 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 77987d11-db65-48a1-b9c5-a1a1112ea0d9, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:13:10,797 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:13:20,804 - DEBUG - ydb.query.pool.1140674  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:13:20,805 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ExecuteQuery, 1a19b49e-9aa1-4b92-a752-f22a352999ac, ghrun-acwccf6kvy.auto.internal:63750): creating call state
2025-11-21 14:13:20,805 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ExecuteQuery, 1a19b49e-9aa1-4b92-a752-f22a352999ac, ghrun-acwccf6kvy.auto.internal:63750): request = { session_id: "ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:13:20,807 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ExecuteQuery, 1a19b49e-9aa1-4b92-a752-f22a352999ac, ghrun-acwccf6kvy.auto.internal:63750): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:13:21,391 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1140674  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 14:13:21,392 - DEBUG - ydb.connection.1140674  - 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 14:13:21,407 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1140674  - context_resolve: Resolving endpoints for database /Root
2025-11-21 14:13:21,408 - DEBUG - ydb.connection.1140674  - _prepare_call: RpcState(ListEndpoints, 560e1ea8-69f2-432e-ad40-7974b0d4c342, localhost:63750): creating call state
2025-11-21 14:13:21,409 - DEBUG - ydb.connection.1140674  - _log_request: RpcState(ListEndpoints, 560e1ea8-69f2-432e-ad40-7974b0d4c342, localhost:63750): request = { database: "/Root" }
2025-11-21 14:13:21,565 - DEBUG - ydb.connection.1140674  - _log_response: RpcState(ListEndpoints, 560e1ea8-69f2-432e-ad40-7974b0d4c342, localhost:63750): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-acwccf6kvy.auto.internal" port: 63750 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 14:13:21,566 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1140674  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-acwccf6kvy.auto.internal:63750, location 1, ssl: False>]>
2025-11-21 14:13:21,567 - DEBUG - ydb.connection.1140674  - close: Closing channel for endpoint localhost:63750
2025-11-21 14:13:21,895 - DEBUG - ydb.query.pool.1140674  - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZhMWRhNGQtYzAzNjdjNTgtOTY5ZWJhMzUtYTY1NjkzNjk=
2025-11-21 14:13:21,899 - INFO - root.1140674  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1497984)
2025-11-21 14:13:21,900 - INFO - root.1140674  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_16_compression` 8009216 / 1497984: 5.346663248739639
2025-11-21 14:13:21,902 - INFO - ya.test.1140674  - pytest_runtest_teardown: Test teardown
