2025-10-16 17:22:29,765 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:22:29,766 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11]
2025-10-16 17:22:29,766 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:22:29,766 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 17:22:29,769 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11])
2025-10-16 17:22:29,770 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:29,771 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 12be10b8-fa5d-4e1b-b7fa-83ae2c15432f, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:29,771 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 12be10b8-fa5d-4e1b-b7fa-83ae2c15432f, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_11_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 11)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:29,773 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 12be10b8-fa5d-4e1b-b7fa-83ae2c15432f, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:33,561 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:33,561 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_11_compression created
2025-10-16 17:22:33,562 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:33,563 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd342f06-a683-4b77-bccc-d60d7ea1bee2, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:33,564 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd342f06-a683-4b77-bccc-d60d7ea1bee2, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_11_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:33,566 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd342f06-a683-4b77-bccc-d60d7ea1bee2, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:34,641 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:34,642 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:34,643 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 74d291bd-6b65-4cb0-a10a-da90fcca6b84, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:34,644 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 74d291bd-6b65-4cb0-a10a-da90fcca6b84, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:34,652 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 74d291bd-6b65-4cb0-a10a-da90fcca6b84, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:37,616 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:37,617 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:37,618 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d2926cf-3d3f-4fac-afe2-94d0b87f80d4, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:37,619 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d2926cf-3d3f-4fac-afe2-94d0b87f80d4, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:37,625 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d2926cf-3d3f-4fac-afe2-94d0b87f80d4, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:39,150 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:39,151 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:39,152 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 860374f4-56cf-42d7-bd7f-632399709699, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:39,153 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 860374f4-56cf-42d7-bd7f-632399709699, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:39,154 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 860374f4-56cf-42d7-bd7f-632399709699, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:41,025 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:41,026 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 375776, 'BlobCount': 128}}
2025-10-16 17:22:41,026 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:41,027 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5b69b91-dfdb-4b79-9e1c-aa5b8422ba93, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:41,031 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5b69b91-dfdb-4b79-9e1c-aa5b8422ba93, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:41,033 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5b69b91-dfdb-4b79-9e1c-aa5b8422ba93, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:43,290 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:43,291 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:43,291 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0bbbf05b-d08f-4cc8-80ca-1ce5fa25a66d, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:43,292 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0bbbf05b-d08f-4cc8-80ca-1ce5fa25a66d, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:43,296 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0bbbf05b-d08f-4cc8-80ca-1ce5fa25a66d, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:43,595 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:43,595 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:43,596 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d448812-0463-4df0-acee-a4017df487dc, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:43,597 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d448812-0463-4df0-acee-a4017df487dc, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:43,598 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d448812-0463-4df0-acee-a4017df487dc, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:44,019 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:44,020 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 753008, 'BlobCount': 256}}
2025-10-16 17:22:44,021 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:44,022 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6dadf040-4e9b-49fd-bcd3-f1a98c1cf0e7, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:44,023 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6dadf040-4e9b-49fd-bcd3-f1a98c1cf0e7, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:44,024 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6dadf040-4e9b-49fd-bcd3-f1a98c1cf0e7, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:46,153 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:46,154 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:46,155 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 669a6a96-a962-4fa5-a05e-ea25b3694cc7, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:46,156 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 669a6a96-a962-4fa5-a05e-ea25b3694cc7, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:46,157 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 669a6a96-a962-4fa5-a05e-ea25b3694cc7, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:46,405 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:46,406 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:46,406 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f216e250-1885-489f-89fd-91e8e8581edb, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:46,407 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f216e250-1885-489f-89fd-91e8e8581edb, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:46,409 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f216e250-1885-489f-89fd-91e8e8581edb, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:46,772 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:46,772 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 183}}, blobs: {'__DEFAULT': {'Portions': 183, 'BlobSize': 1115344, 'BlobCount': 384}}
2025-10-16 17:22:46,773 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:46,773 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ffeb0524-fc14-477a-94df-d2eef01d20ac, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:46,774 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ffeb0524-fc14-477a-94df-d2eef01d20ac, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:46,776 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ffeb0524-fc14-477a-94df-d2eef01d20ac, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:49,324 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:49,325 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:49,326 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f66dd65-8c8a-4cc6-8443-1e2465758dee, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:49,327 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f66dd65-8c8a-4cc6-8443-1e2465758dee, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:49,328 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f66dd65-8c8a-4cc6-8443-1e2465758dee, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:49,746 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:49,747 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:49,748 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e35b2a84-d290-4b86-bdc4-22da2b0977f3, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:49,749 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e35b2a84-d290-4b86-bdc4-22da2b0977f3, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:49,750 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e35b2a84-d290-4b86-bdc4-22da2b0977f3, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:50,323 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:50,324 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 239}}, blobs: {'__DEFAULT': {'Portions': 239, 'BlobSize': 1480960, 'BlobCount': 504}}
2025-10-16 17:22:50,324 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:50,325 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0ebf3559-2b32-4382-addb-959c5139bd16, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:50,326 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0ebf3559-2b32-4382-addb-959c5139bd16, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:50,328 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0ebf3559-2b32-4382-addb-959c5139bd16, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:52,821 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:52,822 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:52,822 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3aa7fba1-8db7-4787-b28a-3359b7ca9468, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:52,823 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3aa7fba1-8db7-4787-b28a-3359b7ca9468, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:52,830 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3aa7fba1-8db7-4787-b28a-3359b7ca9468, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:53,118 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:53,118 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:53,119 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 46785b31-5c2e-44dc-8dcf-8a835be055d6, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:53,120 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 46785b31-5c2e-44dc-8dcf-8a835be055d6, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:53,121 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 46785b31-5c2e-44dc-8dcf-8a835be055d6, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:53,526 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:53,527 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 285}}, blobs: {'__DEFAULT': {'Portions': 285, 'BlobSize': 1832888, 'BlobCount': 608}}
2025-10-16 17:22:53,527 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:53,528 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 139d5482-7ae1-4092-8c61-e91945493013, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:53,529 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 139d5482-7ae1-4092-8c61-e91945493013, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:53,530 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 139d5482-7ae1-4092-8c61-e91945493013, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:55,935 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:55,936 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:55,937 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db3b651c-5c18-46dd-922d-414372d3744e, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:55,938 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db3b651c-5c18-46dd-922d-414372d3744e, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:55,941 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db3b651c-5c18-46dd-922d-414372d3744e, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:56,430 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:56,431 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:56,432 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cb7ec180-c4e7-4cb0-929c-0e7f521ab9e6, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:56,433 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cb7ec180-c4e7-4cb0-929c-0e7f521ab9e6, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:56,435 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cb7ec180-c4e7-4cb0-929c-0e7f521ab9e6, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:57,025 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:57,026 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 341}}, blobs: {'__DEFAULT': {'Portions': 341, 'BlobSize': 2199440, 'BlobCount': 724}}
2025-10-16 17:22:57,027 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:57,027 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70ee9f4b-0eac-4aa5-a437-c752a58cf76d, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:57,031 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70ee9f4b-0eac-4aa5-a437-c752a58cf76d, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:57,033 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70ee9f4b-0eac-4aa5-a437-c752a58cf76d, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:59,631 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:59,632 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:59,633 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ababd7b2-6680-4c10-8257-2de2d258a01d, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:59,634 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ababd7b2-6680-4c10-8257-2de2d258a01d, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:59,644 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ababd7b2-6680-4c10-8257-2de2d258a01d, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:59,998 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:59,999 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:00,000 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 25089505-3942-4279-9dc8-b52c5a0e6c02, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:00,006 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 25089505-3942-4279-9dc8-b52c5a0e6c02, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:00,008 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 25089505-3942-4279-9dc8-b52c5a0e6c02, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:00,529 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:00,530 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 395}}, blobs: {'__DEFAULT': {'Portions': 395, 'BlobSize': 2563192, 'BlobCount': 836}}
2025-10-16 17:23:00,530 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:00,531 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 044ce2d3-3dd5-42d7-885c-279bcd2da216, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:00,532 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 044ce2d3-3dd5-42d7-885c-279bcd2da216, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:00,534 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 044ce2d3-3dd5-42d7-885c-279bcd2da216, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:02,691 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:02,692 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:02,692 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1af0be0c-d410-4bf0-b62c-a19c52b79788, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:02,693 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1af0be0c-d410-4bf0-b62c-a19c52b79788, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:02,695 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1af0be0c-d410-4bf0-b62c-a19c52b79788, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:03,006 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:03,007 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:03,008 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d8a5f5ee-1bcb-4cf2-9dd1-a71526784a1d, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:03,009 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d8a5f5ee-1bcb-4cf2-9dd1-a71526784a1d, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:03,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d8a5f5ee-1bcb-4cf2-9dd1-a71526784a1d, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:03,667 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:03,668 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 447}}, blobs: {'__DEFAULT': {'Portions': 447, 'BlobSize': 2924544, 'BlobCount': 944}}
2025-10-16 17:23:03,669 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:03,670 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 169ab8b8-3bae-45d1-905a-f8094417971f, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:03,671 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 169ab8b8-3bae-45d1-905a-f8094417971f, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:03,677 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 169ab8b8-3bae-45d1-905a-f8094417971f, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:05,904 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:05,905 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:05,906 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70d6efd2-ba97-4d89-8cd3-27994eb2b0b0, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:05,907 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70d6efd2-ba97-4d89-8cd3-27994eb2b0b0, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:05,909 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70d6efd2-ba97-4d89-8cd3-27994eb2b0b0, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:06,431 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:06,432 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:06,433 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e87ec3bf-6c17-4e2a-a96e-0b3e92e3e151, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:06,434 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e87ec3bf-6c17-4e2a-a96e-0b3e92e3e151, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:06,438 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e87ec3bf-6c17-4e2a-a96e-0b3e92e3e151, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:07,133 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:07,134 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 504}}, blobs: {'__DEFAULT': {'Portions': 504, 'BlobSize': 3292568, 'BlobCount': 1060}}
2025-10-16 17:23:07,135 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:07,135 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9fce65ac-488d-4e82-8ce2-f3d014cde21c, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:07,141 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9fce65ac-488d-4e82-8ce2-f3d014cde21c, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:07,143 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9fce65ac-488d-4e82-8ce2-f3d014cde21c, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:10,139 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:10,140 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:10,141 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4e732f0d-78a5-48c2-9348-e336c8ff48cd, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:10,142 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4e732f0d-78a5-48c2-9348-e336c8ff48cd, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:10,143 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4e732f0d-78a5-48c2-9348-e336c8ff48cd, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:10,691 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:10,692 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:10,693 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e8743700-a94c-43a6-8219-5ac352821fed, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:10,694 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e8743700-a94c-43a6-8219-5ac352821fed, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:10,695 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e8743700-a94c-43a6-8219-5ac352821fed, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:11,509 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:11,510 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 544}}, blobs: {'__DEFAULT': {'Portions': 544, 'BlobSize': 3639072, 'BlobCount': 1146}}
2025-10-16 17:23:11,510 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:11,511 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4a124956-4328-494c-9900-f84aed7d1cc5, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:11,511 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4a124956-4328-494c-9900-f84aed7d1cc5, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_11_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:11,517 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4a124956-4328-494c-9900-f84aed7d1cc5, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:12,611 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:12,612 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:12,613 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d78a0d27-2893-459e-bfe3-175f4b254bba, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:12,614 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d78a0d27-2893-459e-bfe3-175f4b254bba, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:12,616 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d78a0d27-2893-459e-bfe3-175f4b254bba, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:12,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:12,955 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:12,955 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ccddfea5-dc92-4b76-9e59-c470b54cfad2, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:12,956 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ccddfea5-dc92-4b76-9e59-c470b54cfad2, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:12,958 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ccddfea5-dc92-4b76-9e59-c470b54cfad2, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:13,248 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:13,249 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:13,250 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2e24edf6-8833-4730-ae6e-8ebadc85b077, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:13,251 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2e24edf6-8833-4730-ae6e-8ebadc85b077, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:13,254 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2e24edf6-8833-4730-ae6e-8ebadc85b077, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:17,119 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:22,492 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:23:22,493 - 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-10-16 17:23:22,516 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:23:22,517 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, fc0d08db-8e7c-4525-8fa0-6e778eed6c27, localhost:3963): creating call state
2025-10-16 17:23:22,518 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, fc0d08db-8e7c-4525-8fa0-6e778eed6c27, localhost:3963): request = { database: "/Root" }
2025-10-16 17:23:22,538 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, fc0d08db-8e7c-4525-8fa0-6e778eed6c27, localhost:3963): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 3963 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-10-16 17:23:22,539 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:3963, location 1, ssl: False>]>
2025-10-16 17:23:22,540 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:3963
2025-10-16 17:23:27,212 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:27,213 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d7288cc-96db-4889-9ea7-94ad0884fcce, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:27,214 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d7288cc-96db-4889-9ea7-94ad0884fcce, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:27,215 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d7288cc-96db-4889-9ea7-94ad0884fcce, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:30,875 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:30,961 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1806640)
2025-10-16 17:23:30,961 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:30,962 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2bfbe215-a3eb-4098-a8f1-f69707fd40f3, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:30,963 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2bfbe215-a3eb-4098-a8f1-f69707fd40f3, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:30,965 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2bfbe215-a3eb-4098-a8f1-f69707fd40f3, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:31,346 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:31,347 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:31,348 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d27418f4-ca68-4453-9d2d-3c3abb8cc0d6, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:31,349 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d27418f4-ca68-4453-9d2d-3c3abb8cc0d6, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:31,350 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d27418f4-ca68-4453-9d2d-3c3abb8cc0d6, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:34,612 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:44,648 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:44,649 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 73b99b36-4005-4d07-a968-fe72e203144f, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:44,650 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 73b99b36-4005-4d07-a968-fe72e203144f, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:44,652 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 73b99b36-4005-4d07-a968-fe72e203144f, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:47,059 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:23:47,075 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1577216)
2025-10-16 17:23:47,076 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_11_compression` 8077040 / 1577216: 5.121074095114429
2025-10-16 17:23:47,078 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-16 17:23:47,079 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 32784f8c-f0bf-4e17-9dfc-81f08dfa40d0, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:23:47,080 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 32784f8c-f0bf-4e17-9dfc-81f08dfa40d0, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" }
2025-10-16 17:23:47,084 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 32784f8c-f0bf-4e17-9dfc-81f08dfa40d0, ghrun-2kszfvc6cm.auto.internal:3963): response = { status: SUCCESS }
2025-10-16 17:23:47,086 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-16 17:23:47,087 - DEBUG - ydb.topic - close: Close topic client
2025-10-16 17:23:47,087 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-16 17:23:47,088 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:3963
2025-10-16 17:23:47,091 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, c8be03a2-7075-4d1b-b986-ea321d81bbe9, ghrun-2kszfvc6cm.auto.internal:3963): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-10-16 17:23:47,100 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-2kszfvc6cm.auto.internal:3963
2025-10-16 17:23:47,102 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-16 17:24:04,137 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:3963/1
2025-10-16 17:24:04,138 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1376
2025-10-16 17:24:04,139 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15201
2025-10-16 17:24:04,139 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21449
2025-10-16 17:24:04,140 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3963
2025-10-16 17:24:04,140 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28037
