2025-12-05 17:12:37,007 - INFO - ya.test.785311  - pytest_runtest_setup: ####################################################################################################
2025-12-05 17:12:37,008 - INFO - ya.test.785311  - pytest_runtest_setup: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14]
2025-12-05 17:12:37,008 - INFO - ya.test.785311  - pytest_runtest_setup: ####################################################################################################
2025-12-05 17:12:37,009 - INFO - ya.test.785311  - pytest_runtest_setup: Test setup
2025-12-05 17:12:37,011 - INFO - ya.test.785311  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14])
2025-12-05 17:12:37,012 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:37,013 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 940bf13c-dcab-4149-bd0c-9b97a2348b13, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:37,015 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 940bf13c-dcab-4149-bd0c-9b97a2348b13, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_14_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 14)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:37,017 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 940bf13c-dcab-4149-bd0c-9b97a2348b13, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:40,733 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:40,734 - INFO - __tests__.alter_compression.785311  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_14_compression created
2025-12-05 17:12:40,734 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:40,735 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 34bb83e6-b158-41c2-b13b-7d7034303e16, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:40,736 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 34bb83e6-b158-41c2-b13b-7d7034303e16, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:40,741 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 34bb83e6-b158-41c2-b13b-7d7034303e16, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:41,781 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:41,786 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:41,790 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 38e6e12b-9ce2-44be-b960-d2ec0a4c5b51, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:41,791 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 38e6e12b-9ce2-44be-b960-d2ec0a4c5b51, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:41,794 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 38e6e12b-9ce2-44be-b960-d2ec0a4c5b51, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:45,770 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:45,771 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:45,772 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 0b18407d-a519-418b-801c-a2a1782fc4e1, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:45,778 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 0b18407d-a519-418b-801c-a2a1782fc4e1, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:45,787 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 0b18407d-a519-418b-801c-a2a1782fc4e1, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:46,923 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:46,924 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:46,936 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 3b0b6564-a722-4a7a-881c-1aa85fea1e40, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:46,940 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 3b0b6564-a722-4a7a-881c-1aa85fea1e40, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:46,946 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 3b0b6564-a722-4a7a-881c-1aa85fea1e40, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:49,062 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:49,064 - INFO - __tests__.alter_compression.785311  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-12-05 17:12:49,066 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:49,067 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 3e812569-dc0f-4474-a013-f5705aa60d7e, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:49,067 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 3e812569-dc0f-4474-a013-f5705aa60d7e, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:49,075 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 3e812569-dc0f-4474-a013-f5705aa60d7e, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:52,473 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:52,474 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:52,490 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 8f948a32-81f3-4812-a193-e74c0ae2a7e6, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:52,498 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 8f948a32-81f3-4812-a193-e74c0ae2a7e6, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:52,504 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 8f948a32-81f3-4812-a193-e74c0ae2a7e6, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:52,825 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:52,825 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:52,826 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 9d54240d-086f-41ea-9ff3-3d224884657c, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:52,834 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 9d54240d-086f-41ea-9ff3-3d224884657c, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:52,839 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 9d54240d-086f-41ea-9ff3-3d224884657c, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:53,246 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:53,247 - INFO - __tests__.alter_compression.785311  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-12-05 17:12:53,254 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:53,254 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 8a21c513-1119-4311-8b7b-2575ee7f7dce, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:53,258 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 8a21c513-1119-4311-8b7b-2575ee7f7dce, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:53,259 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 8a21c513-1119-4311-8b7b-2575ee7f7dce, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:56,369 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:56,370 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:56,382 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 65382935-f6fa-4432-b760-9331649e5fc2, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:56,395 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 65382935-f6fa-4432-b760-9331649e5fc2, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:56,408 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 65382935-f6fa-4432-b760-9331649e5fc2, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:56,892 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:56,904 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:56,906 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, ff34efad-a8c6-4bf3-aa0b-85825ee556fa, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:56,907 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, ff34efad-a8c6-4bf3-aa0b-85825ee556fa, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:56,908 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, ff34efad-a8c6-4bf3-aa0b-85825ee556fa, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:57,579 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:57,590 - INFO - __tests__.alter_compression.785311  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-12-05 17:12:57,610 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:12:57,618 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 3c2b47ed-53c2-4ad6-b641-55d9b131838a, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:12:57,619 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 3c2b47ed-53c2-4ad6-b641-55d9b131838a, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:12:57,627 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 3c2b47ed-53c2-4ad6-b641-55d9b131838a, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:12:59,369 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.785311  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 17:12:59,370 - DEBUG - ydb.connection.785311  - 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-12-05 17:12:59,395 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.785311  - context_resolve: Resolving endpoints for database /Root
2025-12-05 17:12:59,398 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ListEndpoints, 5b89fe40-d110-43f6-b254-d8fd85496efd, localhost:28319): creating call state
2025-12-05 17:12:59,403 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ListEndpoints, 5b89fe40-d110-43f6-b254-d8fd85496efd, localhost:28319): request = { database: "/Root" }
2025-12-05 17:12:59,413 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ListEndpoints, 5b89fe40-d110-43f6-b254-d8fd85496efd, localhost:28319): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 28319 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-12-05 17:12:59,414 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.785311  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:28319, location 1, ssl: False>]>
2025-12-05 17:12:59,415 - DEBUG - ydb.connection.785311  - close: Closing channel for endpoint localhost:28319
2025-12-05 17:13:00,912 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:00,929 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:00,930 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, e8761171-814d-48c8-9c17-d14b5fe45660, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:00,931 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, e8761171-814d-48c8-9c17-d14b5fe45660, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:00,933 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, e8761171-814d-48c8-9c17-d14b5fe45660, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:01,468 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:01,469 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:01,470 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, d2e21052-1734-4357-9c0d-693234cbcfe0, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:01,471 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, d2e21052-1734-4357-9c0d-693234cbcfe0, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:01,473 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, d2e21052-1734-4357-9c0d-693234cbcfe0, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:02,169 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:02,170 - INFO - __tests__.alter_compression.785311  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-12-05 17:13:02,170 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:02,171 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, d96fb521-8d97-4329-b19c-da7fff1eb2b4, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:02,175 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, d96fb521-8d97-4329-b19c-da7fff1eb2b4, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:02,177 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, d96fb521-8d97-4329-b19c-da7fff1eb2b4, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:05,972 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:05,973 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:05,974 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, d0c5361c-3e33-4dae-b802-6deb01fdbee4, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:05,976 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, d0c5361c-3e33-4dae-b802-6deb01fdbee4, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:05,978 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, d0c5361c-3e33-4dae-b802-6deb01fdbee4, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:06,374 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:06,379 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:06,382 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 475fdc93-90e5-4849-9808-d96b53895238, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:06,390 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 475fdc93-90e5-4849-9808-d96b53895238, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:06,392 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 475fdc93-90e5-4849-9808-d96b53895238, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:07,079 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:07,097 - INFO - __tests__.alter_compression.785311  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-12-05 17:13:07,104 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:07,111 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 3e61f93f-7ea2-41fc-af69-5ef144e75b9a, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:07,112 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 3e61f93f-7ea2-41fc-af69-5ef144e75b9a, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:07,114 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 3e61f93f-7ea2-41fc-af69-5ef144e75b9a, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:10,359 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:10,365 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:10,378 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 0e607f54-1a32-46f9-888f-4184ec95387f, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:10,379 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 0e607f54-1a32-46f9-888f-4184ec95387f, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:10,381 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 0e607f54-1a32-46f9-888f-4184ec95387f, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:10,807 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:10,814 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:10,826 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 362d2b0b-a917-4bc1-9e23-13a6b51cbb96, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:10,837 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 362d2b0b-a917-4bc1-9e23-13a6b51cbb96, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:10,849 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 362d2b0b-a917-4bc1-9e23-13a6b51cbb96, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:11,536 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:11,555 - INFO - __tests__.alter_compression.785311  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-12-05 17:13:11,556 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:11,571 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, d47591d9-0433-4875-bc73-b952aa6df759, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:11,573 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, d47591d9-0433-4875-bc73-b952aa6df759, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:11,583 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, d47591d9-0433-4875-bc73-b952aa6df759, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:15,205 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:15,206 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:15,226 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 4bcdfd3c-89f7-4bd4-8c4d-fb0f9e79e0b7, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:15,227 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 4bcdfd3c-89f7-4bd4-8c4d-fb0f9e79e0b7, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:15,228 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 4bcdfd3c-89f7-4bd4-8c4d-fb0f9e79e0b7, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:15,717 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:15,722 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:15,734 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 5b2b8ea0-a741-43bd-9c9e-82df7cd39f2f, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:15,736 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 5b2b8ea0-a741-43bd-9c9e-82df7cd39f2f, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:15,743 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 5b2b8ea0-a741-43bd-9c9e-82df7cd39f2f, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:16,594 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:16,600 - INFO - __tests__.alter_compression.785311  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-12-05 17:13:16,602 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:16,603 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 07856411-9325-4c58-86a3-e1469c9b55a2, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:16,606 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 07856411-9325-4c58-86a3-e1469c9b55a2, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:16,608 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 07856411-9325-4c58-86a3-e1469c9b55a2, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:20,103 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:20,107 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:20,113 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, cb34c857-b95a-4de4-af64-503b406c1e75, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:20,118 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, cb34c857-b95a-4de4-af64-503b406c1e75, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:20,127 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, cb34c857-b95a-4de4-af64-503b406c1e75, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:20,486 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:20,495 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:20,503 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 4d679164-b620-4bd6-be73-819ceb6f0bcb, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:20,511 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 4d679164-b620-4bd6-be73-819ceb6f0bcb, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:20,519 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 4d679164-b620-4bd6-be73-819ceb6f0bcb, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:21,379 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:21,379 - INFO - __tests__.alter_compression.785311  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-12-05 17:13:21,380 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:21,401 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, e2c38cea-5ce4-489c-b4cf-b1394512d180, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:21,403 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, e2c38cea-5ce4-489c-b4cf-b1394512d180, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:21,408 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, e2c38cea-5ce4-489c-b4cf-b1394512d180, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:25,102 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:25,103 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:25,106 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 988d25d2-97a2-4b2b-8da2-c3a1a56e34ef, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:25,108 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 988d25d2-97a2-4b2b-8da2-c3a1a56e34ef, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:25,116 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 988d25d2-97a2-4b2b-8da2-c3a1a56e34ef, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:25,750 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:25,751 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:25,752 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 9b354ec1-5fe8-44d3-adf8-bce60ab88966, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:25,766 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 9b354ec1-5fe8-44d3-adf8-bce60ab88966, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:25,767 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 9b354ec1-5fe8-44d3-adf8-bce60ab88966, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:27,007 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:27,010 - INFO - __tests__.alter_compression.785311  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-12-05 17:13:27,014 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:27,019 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 90006533-67de-4767-a5a7-b7b3035b3b73, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:27,023 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 90006533-67de-4767-a5a7-b7b3035b3b73, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:27,031 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 90006533-67de-4767-a5a7-b7b3035b3b73, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:31,218 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:31,219 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:31,220 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 86f421c3-1154-47b7-b3e8-11608a386302, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:31,230 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 86f421c3-1154-47b7-b3e8-11608a386302, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:31,231 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 86f421c3-1154-47b7-b3e8-11608a386302, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:31,746 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:31,746 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:31,751 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, d0f8e980-83f2-41a5-a995-a0c0958728a8, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:31,753 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, d0f8e980-83f2-41a5-a995-a0c0958728a8, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:31,755 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, d0f8e980-83f2-41a5-a995-a0c0958728a8, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:33,438 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:33,438 - INFO - __tests__.alter_compression.785311  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-12-05 17:13:33,441 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:33,468 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, b4f807c9-e433-488c-90ea-3eae3aaf7823, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:33,469 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, b4f807c9-e433-488c-90ea-3eae3aaf7823, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:33,471 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, b4f807c9-e433-488c-90ea-3eae3aaf7823, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:35,699 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:35,706 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:35,715 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 7c11893e-0471-4164-ac31-e830fb2e7c2d, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:35,722 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 7c11893e-0471-4164-ac31-e830fb2e7c2d, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:35,741 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 7c11893e-0471-4164-ac31-e830fb2e7c2d, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:36,431 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:36,432 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:36,439 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 43040225-c819-4cff-89bc-f5029480fa9b, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:36,440 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 43040225-c819-4cff-89bc-f5029480fa9b, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:36,447 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 43040225-c819-4cff-89bc-f5029480fa9b, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:37,031 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:37,032 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:37,036 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, b6c401b1-f1b0-42f8-a446-4ae94f3e976f, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:37,038 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, b6c401b1-f1b0-42f8-a446-4ae94f3e976f, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:37,039 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, b6c401b1-f1b0-42f8-a446-4ae94f3e976f, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:45,556 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:55,605 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:13:55,606 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, c4291862-b653-496e-b1aa-7469af4c2c72, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:13:55,614 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, c4291862-b653-496e-b1aa-7469af4c2c72, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:13:55,616 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, c4291862-b653-496e-b1aa-7469af4c2c72, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:13:57,872 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:07,893 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:07,898 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, abe3e960-b2b2-42d1-94fb-8d6983d223de, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:14:07,899 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, abe3e960-b2b2-42d1-94fb-8d6983d223de, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:14:07,903 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, abe3e960-b2b2-42d1-94fb-8d6983d223de, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:14:08,758 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:18,766 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:18,768 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 96b28eaa-0242-4639-91e2-5ec7cee2652b, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:14:18,770 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 96b28eaa-0242-4639-91e2-5ec7cee2652b, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:14:18,774 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 96b28eaa-0242-4639-91e2-5ec7cee2652b, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:14:19,550 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:19,560 - INFO - root.785311  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1870856)
2025-12-05 17:14:19,561 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:19,565 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, 5ed4a071-db0f-4132-b8a0-0784a71a60d2, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:14:19,566 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, 5ed4a071-db0f-4132-b8a0-0784a71a60d2, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:14:19,568 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, 5ed4a071-db0f-4132-b8a0-0784a71a60d2, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:14:19,829 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:19,830 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:19,832 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, af619eb4-0d7b-446c-908a-2688179eae38, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:14:19,833 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, af619eb4-0d7b-446c-908a-2688179eae38, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:14:19,835 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, af619eb4-0d7b-446c-908a-2688179eae38, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:14:20,515 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:30,520 - DEBUG - ydb.query.pool.785311  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:30,521 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(ExecuteQuery, a9a85e68-2069-4991-abc1-6f115d5491ec, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:14:30,522 - DEBUG - ydb.connection.785311  - _log_request: RpcState(ExecuteQuery, a9a85e68-2069-4991-abc1-6f115d5491ec, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:14:30,523 - DEBUG - ydb.connection.785311  - _log_response: RpcState(ExecuteQuery, a9a85e68-2069-4991-abc1-6f115d5491ec, ghrun-a66hhqwkt4.auto.internal:28319): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:14:31,187 - DEBUG - ydb.query.pool.785311  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=
2025-12-05 17:14:31,190 - INFO - root.785311  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1870856)
2025-12-05 17:14:31,190 - INFO - root.785311  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_14_compression` 8092160 / 1870856: 4.325378329491954
2025-12-05 17:14:31,192 - INFO - ya.test.785311  - pytest_runtest_teardown: Test teardown
2025-12-05 17:14:31,193 - DEBUG - ydb.connection.785311  - _prepare_call: RpcState(DeleteSession, 4a6cce88-100d-4f44-8739-4864d9d3e6ab, ghrun-a66hhqwkt4.auto.internal:28319): creating call state
2025-12-05 17:14:31,194 - DEBUG - ydb.connection.785311  - _log_request: RpcState(DeleteSession, 4a6cce88-100d-4f44-8739-4864d9d3e6ab, ghrun-a66hhqwkt4.auto.internal:28319): request = { session_id: "ydb://session/3?node_id=1&id=MmIwN2QzNjMtM2ZjNDJhMTctYTkxMjJhNjMtMWJiNzA3NzQ=" }
2025-12-05 17:14:31,197 - DEBUG - ydb.connection.785311  - _log_response: RpcState(DeleteSession, 4a6cce88-100d-4f44-8739-4864d9d3e6ab, ghrun-a66hhqwkt4.auto.internal:28319): response = { status: SUCCESS }
2025-12-05 17:14:31,199 - DEBUG - ydb.query.pool.785311  - stop: All session were deleted.
2025-12-05 17:14:31,200 - DEBUG - ydb.topic.785311  - close: Close topic client
2025-12-05 17:14:31,200 - DEBUG - ydb.connection.785311  - _rpc_error_handler: RpcState(AttachSession, a7207c6c-7783-4a5e-9b07-8176388800e7, ghrun-a66hhqwkt4.auto.internal:28319): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-05 17:14:31,202 - DEBUG - ydb.topic.785311  - close: Topic client was closed
2025-12-05 17:14:31,206 - DEBUG - ydb.connection.785311  - close: Closing channel for endpoint localhost:28319
2025-12-05 17:14:31,206 - DEBUG - ydb.connection.785311  - close: Closing channel for endpoint ghrun-a66hhqwkt4.auto.internal:28319
2025-12-05 17:14:31,208 - INFO - ydb.pool.Discovery.785311  - run: Successfully terminated discovery process
2025-12-05 17:14:43,211 - INFO - ydb.tests.library.harness.kikimr_runner.785311  - stop: Stopped node localhost:28319/1
2025-12-05 17:14:43,213 - DEBUG - library.python.filelock.785311  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3309
2025-12-05 17:14:43,213 - DEBUG - library.python.filelock.785311  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27256
2025-12-05 17:14:43,213 - DEBUG - library.python.filelock.785311  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3839
2025-12-05 17:14:43,214 - DEBUG - library.python.filelock.785311  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28319
2025-12-05 17:14:43,214 - DEBUG - library.python.filelock.785311  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1773
