2025-11-06 10:16:04,426 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-06 10:16:04,426 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_9_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 9]
2025-11-06 10:16:04,427 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-06 10:16:04,427 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-06 10:16:04,429 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_9_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 9])
2025-11-06 10:16:04,429 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:04,430 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c30f8097-08ec-43b9-a11e-3888412d8bac, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:04,431 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c30f8097-08ec-43b9-a11e-3888412d8bac, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_9_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 9)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:04,432 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c30f8097-08ec-43b9-a11e-3888412d8bac, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:07,771 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:07,771 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_9_compression created
2025-11-06 10:16:07,772 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:07,772 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96d2b434-604f-445a-9aae-d43c739d83a9, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:07,773 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96d2b434-604f-445a-9aae-d43c739d83a9, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_9_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:07,774 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96d2b434-604f-445a-9aae-d43c739d83a9, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:08,941 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:08,942 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:08,943 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, caf22f03-cb14-4c7e-abea-a079f670b09e, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:08,944 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, caf22f03-cb14-4c7e-abea-a079f670b09e, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:08,951 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, caf22f03-cb14-4c7e-abea-a079f670b09e, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:11,085 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:11,086 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:11,086 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 14aa5dd7-33e0-4cdd-b4b7-8cef2f4906d4, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:11,087 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 14aa5dd7-33e0-4cdd-b4b7-8cef2f4906d4, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:11,089 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 14aa5dd7-33e0-4cdd-b4b7-8cef2f4906d4, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:12,030 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:12,031 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:12,032 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e3ce8401-56e7-4484-8bca-e7a462a5f168, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:12,033 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e3ce8401-56e7-4484-8bca-e7a462a5f168, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:12,035 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e3ce8401-56e7-4484-8bca-e7a462a5f168, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:13,986 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:13,987 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318048, 'BlobCount': 128}}
2025-11-06 10:16:13,987 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:13,988 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ea3dab3-bbb1-41db-93fc-b650710f57d3, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:13,988 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ea3dab3-bbb1-41db-93fc-b650710f57d3, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:13,989 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ea3dab3-bbb1-41db-93fc-b650710f57d3, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:15,735 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:15,735 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:15,736 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ed3f8a0-6549-4815-baad-0eb3be148e52, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:15,737 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ed3f8a0-6549-4815-baad-0eb3be148e52, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:15,738 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ed3f8a0-6549-4815-baad-0eb3be148e52, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:15,979 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:15,980 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:15,980 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 823c5713-41bd-47d8-a903-cc216ab5b06a, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:15,981 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 823c5713-41bd-47d8-a903-cc216ab5b06a, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:15,986 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 823c5713-41bd-47d8-a903-cc216ab5b06a, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:16,399 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:16,400 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637344, 'BlobCount': 256}}
2025-11-06 10:16:16,400 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:16,401 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8183da42-7555-4429-8d8c-60047ce93c38, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:16,402 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8183da42-7555-4429-8d8c-60047ce93c38, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:16,403 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8183da42-7555-4429-8d8c-60047ce93c38, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:18,114 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:18,115 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:18,115 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7b35865f-656b-4794-b586-c26980960a83, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:18,116 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7b35865f-656b-4794-b586-c26980960a83, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:18,117 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7b35865f-656b-4794-b586-c26980960a83, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:18,532 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:18,533 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:18,534 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c25ee867-6522-425a-9e66-7cc244f76f5e, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:18,535 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c25ee867-6522-425a-9e66-7cc244f76f5e, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:18,536 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c25ee867-6522-425a-9e66-7cc244f76f5e, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:19,016 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:19,017 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956384, 'BlobCount': 384}}
2025-11-06 10:16:19,017 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:19,018 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ebe1ff3d-3aa0-4047-9162-b0726aed69c7, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:19,019 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ebe1ff3d-3aa0-4047-9162-b0726aed69c7, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:19,020 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ebe1ff3d-3aa0-4047-9162-b0726aed69c7, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:21,211 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:21,212 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:21,212 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d486cc8-91bb-4999-abf4-68b0f60c72d1, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:21,214 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d486cc8-91bb-4999-abf4-68b0f60c72d1, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:21,215 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d486cc8-91bb-4999-abf4-68b0f60c72d1, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:21,539 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:21,540 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:21,541 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f1608f63-1c3f-40f0-b4fa-9c12966ba6fd, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:21,542 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f1608f63-1c3f-40f0-b4fa-9c12966ba6fd, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:21,543 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f1608f63-1c3f-40f0-b4fa-9c12966ba6fd, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:22,171 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:22,172 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275744, 'BlobCount': 512}}
2025-11-06 10:16:22,172 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:22,173 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c37b659e-ce48-48fd-8a98-b0f6d249764c, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:22,174 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c37b659e-ce48-48fd-8a98-b0f6d249764c, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:22,180 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c37b659e-ce48-48fd-8a98-b0f6d249764c, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:23,899 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:23,900 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:23,900 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d27d5ff-0262-4583-8044-8222c3651456, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:23,901 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d27d5ff-0262-4583-8044-8222c3651456, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:23,904 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d27d5ff-0262-4583-8044-8222c3651456, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:24,223 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:24,224 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:24,225 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af9ff136-dd21-4496-b13f-9067b41eb5a3, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:24,225 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af9ff136-dd21-4496-b13f-9067b41eb5a3, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:24,226 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af9ff136-dd21-4496-b13f-9067b41eb5a3, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:24,960 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:24,960 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594704, 'BlobCount': 640}}
2025-11-06 10:16:24,961 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:24,962 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d977603-7cd7-4fd5-8bbc-06a49fa76c05, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:24,967 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d977603-7cd7-4fd5-8bbc-06a49fa76c05, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:24,969 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d977603-7cd7-4fd5-8bbc-06a49fa76c05, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:26,870 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:26,870 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:26,871 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 60bfc6ac-684e-49d2-8a7f-ce16ac95b01d, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:26,872 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 60bfc6ac-684e-49d2-8a7f-ce16ac95b01d, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:26,873 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 60bfc6ac-684e-49d2-8a7f-ce16ac95b01d, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:27,254 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:27,255 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:27,256 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d1b2202b-8ae0-4312-a100-5bca6199f6d2, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:27,257 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d1b2202b-8ae0-4312-a100-5bca6199f6d2, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:27,262 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d1b2202b-8ae0-4312-a100-5bca6199f6d2, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:27,813 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:27,814 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913888, 'BlobCount': 768}}
2025-11-06 10:16:27,815 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:27,815 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 957b3100-d627-4760-95f8-95ccbbb57daf, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:27,816 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 957b3100-d627-4760-95f8-95ccbbb57daf, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:27,823 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 957b3100-d627-4760-95f8-95ccbbb57daf, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:29,318 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:29,319 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:29,320 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f353ab11-0672-45f9-be16-44e1a2ba8f4c, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:29,320 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f353ab11-0672-45f9-be16-44e1a2ba8f4c, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:29,321 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f353ab11-0672-45f9-be16-44e1a2ba8f4c, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:29,603 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:29,604 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:29,605 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6ced3c18-caa7-43b7-bb95-d332eba66e9a, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:29,606 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6ced3c18-caa7-43b7-bb95-d332eba66e9a, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:29,607 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6ced3c18-caa7-43b7-bb95-d332eba66e9a, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:30,233 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:30,233 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233024, 'BlobCount': 896}}
2025-11-06 10:16:30,234 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:30,234 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 77a5f185-8c3e-4119-b9c6-e5728d360623, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:30,235 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 77a5f185-8c3e-4119-b9c6-e5728d360623, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:30,237 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 77a5f185-8c3e-4119-b9c6-e5728d360623, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:31,819 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:31,819 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:31,820 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 67ab7a65-8c45-4584-ba13-34a6f668915b, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:31,820 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 67ab7a65-8c45-4584-ba13-34a6f668915b, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:31,822 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 67ab7a65-8c45-4584-ba13-34a6f668915b, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:32,091 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:32,092 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:32,092 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fb823fa7-8c88-4ae4-9128-13fc617e0542, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:32,093 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fb823fa7-8c88-4ae4-9128-13fc617e0542, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:32,095 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fb823fa7-8c88-4ae4-9128-13fc617e0542, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:32,638 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:32,639 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552512, 'BlobCount': 1024}}
2025-11-06 10:16:32,639 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:32,640 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3a324e2c-1971-4159-8d01-675dc43809a6, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:32,641 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3a324e2c-1971-4159-8d01-675dc43809a6, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:32,642 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3a324e2c-1971-4159-8d01-675dc43809a6, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:33,523 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:16:33,524 - DEBUG - ydb.connection - 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-06 10:16:33,549 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-06 10:16:33,550 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 8eb23382-77ed-4816-9bcc-c5a0269ba235, localhost:6860): creating call state
2025-11-06 10:16:33,550 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 8eb23382-77ed-4816-9bcc-c5a0269ba235, localhost:6860): request = { database: "/Root" }
2025-11-06 10:16:33,560 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 8eb23382-77ed-4816-9bcc-c5a0269ba235, localhost:6860): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ew6xquenpy.auto.internal" port: 6860 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-06 10:16:33,562 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ew6xquenpy.auto.internal:6860, location 1, ssl: False>]>
2025-11-06 10:16:33,562 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6860
2025-11-06 10:16:34,232 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:34,232 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:34,233 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ea01f5d1-5b38-47ca-b30d-492f3d0c2b1f, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:34,234 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ea01f5d1-5b38-47ca-b30d-492f3d0c2b1f, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:34,235 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ea01f5d1-5b38-47ca-b30d-492f3d0c2b1f, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:34,512 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:34,513 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:34,513 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0ded3134-466b-4db3-9831-b72d00365b25, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:34,514 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0ded3134-466b-4db3-9831-b72d00365b25, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:34,516 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0ded3134-466b-4db3-9831-b72d00365b25, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:35,255 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:35,256 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871520, 'BlobCount': 1152}}
2025-11-06 10:16:35,256 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:35,257 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f37e2f3-d586-4088-b241-e7f8ab7d923d, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:35,258 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f37e2f3-d586-4088-b241-e7f8ab7d923d, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:35,259 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f37e2f3-d586-4088-b241-e7f8ab7d923d, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:36,915 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:36,915 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:36,916 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d5c7d331-a701-4671-90fe-0d4e985e16f0, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:36,917 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d5c7d331-a701-4671-90fe-0d4e985e16f0, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:36,918 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d5c7d331-a701-4671-90fe-0d4e985e16f0, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:37,235 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:37,236 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:37,237 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 52f855ef-ff40-432b-afa2-12a3459240ed, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:37,238 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 52f855ef-ff40-432b-afa2-12a3459240ed, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:37,240 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 52f855ef-ff40-432b-afa2-12a3459240ed, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:37,987 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:37,988 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191024, 'BlobCount': 1280}}
2025-11-06 10:16:37,989 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:37,990 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2daba061-3309-42e3-9811-650e5cea2171, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:37,991 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2daba061-3309-42e3-9811-650e5cea2171, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_9_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:37,992 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2daba061-3309-42e3-9811-650e5cea2171, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:39,119 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:39,120 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:39,121 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5ebe1409-681f-4cf9-bd20-533f7260e21c, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:39,122 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5ebe1409-681f-4cf9-bd20-533f7260e21c, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:39,123 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5ebe1409-681f-4cf9-bd20-533f7260e21c, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:39,445 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:39,446 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:39,447 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5a0a7a9f-bff0-428d-b3d2-bb5cb8201e3f, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:39,448 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5a0a7a9f-bff0-428d-b3d2-bb5cb8201e3f, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:39,449 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5a0a7a9f-bff0-428d-b3d2-bb5cb8201e3f, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:39,798 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:39,799 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:39,800 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4c91c961-744c-42e9-97b7-3bb2a3f4ce5b, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:39,801 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4c91c961-744c-42e9-97b7-3bb2a3f4ce5b, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:39,802 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4c91c961-744c-42e9-97b7-3bb2a3f4ce5b, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:43,735 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:53,763 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:53,764 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b04c33e-3c15-4bf3-9f3d-4931de9b061d, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:53,765 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b04c33e-3c15-4bf3-9f3d-4931de9b061d, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:53,767 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b04c33e-3c15-4bf3-9f3d-4931de9b061d, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:57,023 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:57,171 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_9_compression`, volumes `value` (8000000, 1595512)
2025-11-06 10:16:57,171 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:57,172 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0fdf6557-6b7a-4ff9-899c-e8f36f690a73, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:57,173 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0fdf6557-6b7a-4ff9-899c-e8f36f690a73, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:57,182 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0fdf6557-6b7a-4ff9-899c-e8f36f690a73, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:57,579 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:57,580 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:16:57,581 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d83820a0-e011-4f69-ac9b-780ec0dfc80b, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:16:57,582 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d83820a0-e011-4f69-ac9b-780ec0dfc80b, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:57,583 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d83820a0-e011-4f69-ac9b-780ec0dfc80b, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:17:01,132 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:17:11,167 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:17:11,168 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be1947d4-7646-4a81-be9a-1cf494f6c275, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:17:11,169 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be1947d4-7646-4a81-be9a-1cf494f6c275, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:17:11,170 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be1947d4-7646-4a81-be9a-1cf494f6c275, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:17:14,391 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:17:24,479 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:17:24,480 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0aedab9f-bee3-42b5-a1bf-927630a677d8, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:17:24,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0aedab9f-bee3-42b5-a1bf-927630a677d8, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:17:24,482 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0aedab9f-bee3-42b5-a1bf-927630a677d8, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:17:25,295 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:17:35,302 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:17:35,304 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 21026651-54d4-401a-a4fd-1a90788f4561, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:17:35,304 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 21026651-54d4-401a-a4fd-1a90788f4561, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:17:35,306 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 21026651-54d4-401a-a4fd-1a90788f4561, ghrun-ew6xquenpy.auto.internal:6860): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:17:36,113 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=
2025-11-06 10:17:36,117 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_9_compression`, volumes `value` (8000000, 1465240)
2025-11-06 10:17:36,118 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_9_compression` 8092160 / 1465240: 5.522753951571073
2025-11-06 10:17:36,124 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-06 10:17:36,125 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d2ad0a72-6039-4e5d-9ece-24d5a568ab3e, ghrun-ew6xquenpy.auto.internal:6860): creating call state
2025-11-06 10:17:36,126 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d2ad0a72-6039-4e5d-9ece-24d5a568ab3e, ghrun-ew6xquenpy.auto.internal:6860): request = { session_id: "ydb://session/3?node_id=1&id=ZjQ2NjliMzUtMTc0YmQ2YzgtMmU3MDA3MTktNWI5NDY2YTQ=" }
2025-11-06 10:17:36,130 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d2ad0a72-6039-4e5d-9ece-24d5a568ab3e, ghrun-ew6xquenpy.auto.internal:6860): response = { status: SUCCESS }
2025-11-06 10:17:36,136 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-06 10:17:36,137 - DEBUG - ydb.topic - close: Close topic client
2025-11-06 10:17:36,139 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-06 10:17:36,139 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6860
2025-11-06 10:17:36,140 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-ew6xquenpy.auto.internal:6860
2025-11-06 10:17:36,142 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-06 10:17:48,179 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:6860/1
2025-11-06 10:17:48,183 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22343
2025-11-06 10:17:48,183 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4884
2025-11-06 10:17:48,184 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24243
2025-11-06 10:17:48,184 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6860
2025-11-06 10:17:48,184 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27680
