2025-10-31 14:36:28,586 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 14:36:28,587 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19]
2025-10-31 14:36:28,587 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 14:36:28,587 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 14:36:28,589 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19])
2025-10-31 14:36:28,590 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:28,591 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8c33bdbd-2954-48ad-901f-6d175899e3e3, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:28,592 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8c33bdbd-2954-48ad-901f-6d175899e3e3, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_19_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 19)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:28,595 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8c33bdbd-2954-48ad-901f-6d175899e3e3, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:33,085 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:33,085 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_19_compression created
2025-10-31 14:36:33,086 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:33,087 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 13802e31-f370-4a04-9144-e463fb62e977, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:33,088 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 13802e31-f370-4a04-9144-e463fb62e977, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:33,089 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 13802e31-f370-4a04-9144-e463fb62e977, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:34,319 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:34,320 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:34,321 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c4ca3efe-a626-47a3-a1b5-3ed218b387b8, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:34,322 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c4ca3efe-a626-47a3-a1b5-3ed218b387b8, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:34,326 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c4ca3efe-a626-47a3-a1b5-3ed218b387b8, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:40,208 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:40,209 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:40,210 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 10302eba-8c1f-41e6-b243-816ea4ed24e3, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:40,210 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 10302eba-8c1f-41e6-b243-816ea4ed24e3, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:40,212 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 10302eba-8c1f-41e6-b243-816ea4ed24e3, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:41,402 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:41,403 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:41,404 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d2d4575a-ca41-4bcb-adc7-329cdae59794, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:41,405 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d2d4575a-ca41-4bcb-adc7-329cdae59794, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:41,406 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d2d4575a-ca41-4bcb-adc7-329cdae59794, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:43,645 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:43,646 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-10-31 14:36:43,647 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:43,647 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 13b425a1-1ec5-40b1-8939-2e02d89fd27e, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:43,648 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 13b425a1-1ec5-40b1-8939-2e02d89fd27e, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:43,650 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 13b425a1-1ec5-40b1-8939-2e02d89fd27e, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:48,641 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:48,642 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:48,642 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be96f479-af5f-4c1c-99ee-19e9c15264e3, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:48,643 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be96f479-af5f-4c1c-99ee-19e9c15264e3, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:48,645 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be96f479-af5f-4c1c-99ee-19e9c15264e3, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:49,149 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:49,150 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:49,151 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0c2fcb83-c11b-4f56-999a-bf8a8f489a00, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:49,152 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0c2fcb83-c11b-4f56-999a-bf8a8f489a00, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:49,154 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0c2fcb83-c11b-4f56-999a-bf8a8f489a00, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:49,614 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:49,615 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-10-31 14:36:49,615 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:49,616 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c313a38a-6e55-435a-b990-c64fcbf90653, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:49,617 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c313a38a-6e55-435a-b990-c64fcbf90653, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:49,619 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c313a38a-6e55-435a-b990-c64fcbf90653, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:54,223 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:54,224 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:54,225 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2430110f-bd36-4bfd-85cf-7ba138529da3, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:54,226 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2430110f-bd36-4bfd-85cf-7ba138529da3, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:54,227 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2430110f-bd36-4bfd-85cf-7ba138529da3, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:54,817 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:54,818 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:54,819 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1318315e-d233-40b7-9d4f-d10b1a13e3d0, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:54,820 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1318315e-d233-40b7-9d4f-d10b1a13e3d0, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:54,823 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1318315e-d233-40b7-9d4f-d10b1a13e3d0, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:55,243 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:55,243 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-10-31 14:36:55,244 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:55,245 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 820a43fd-e747-44fb-850a-ed09904af535, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:55,246 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 820a43fd-e747-44fb-850a-ed09904af535, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:55,247 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 820a43fd-e747-44fb-850a-ed09904af535, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:36:59,949 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:59,950 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:36:59,951 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1f44a503-b354-4091-a91b-0668aa3ea9c5, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:36:59,951 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1f44a503-b354-4091-a91b-0668aa3ea9c5, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:36:59,953 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1f44a503-b354-4091-a91b-0668aa3ea9c5, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:00,430 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:00,431 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:00,432 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fc899c77-e524-4031-a30d-0b04acc7b35f, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:00,433 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fc899c77-e524-4031-a30d-0b04acc7b35f, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:00,437 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fc899c77-e524-4031-a30d-0b04acc7b35f, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:01,027 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:01,028 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-10-31 14:37:01,029 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:01,029 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f3e2138a-e325-43ce-a894-469f157f8dc3, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:01,031 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f3e2138a-e325-43ce-a894-469f157f8dc3, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:01,033 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f3e2138a-e325-43ce-a894-469f157f8dc3, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:02,153 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 14:37:02,154 - 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-31 14:37:02,168 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 14:37:02,171 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, f902148a-7c86-491d-8f79-3f7c242ac16b, localhost:11858): creating call state
2025-10-31 14:37:02,171 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, f902148a-7c86-491d-8f79-3f7c242ac16b, localhost:11858): request = { database: "/Root" }
2025-10-31 14:37:02,177 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, f902148a-7c86-491d-8f79-3f7c242ac16b, localhost:11858): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uk32wjuvr4.auto.internal" port: 11858 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-31 14:37:02,178 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uk32wjuvr4.auto.internal:11858, location 1, ssl: False>]>
2025-10-31 14:37:02,179 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:11858
2025-10-31 14:37:05,522 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:05,523 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:05,524 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aaea5457-e6a4-491e-91dc-16b66976d55f, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:05,525 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aaea5457-e6a4-491e-91dc-16b66976d55f, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:05,527 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aaea5457-e6a4-491e-91dc-16b66976d55f, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:05,884 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:05,885 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:05,886 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0333e09c-62f4-4db0-a689-f9ae55227adc, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:05,888 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0333e09c-62f4-4db0-a689-f9ae55227adc, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:05,890 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0333e09c-62f4-4db0-a689-f9ae55227adc, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:06,539 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:06,540 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-10-31 14:37:06,540 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:06,541 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6a5124a0-88db-4e95-b301-5657e4f66f99, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:06,543 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6a5124a0-88db-4e95-b301-5657e4f66f99, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:06,557 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6a5124a0-88db-4e95-b301-5657e4f66f99, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:11,057 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:11,058 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:11,059 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17c0f6b5-cce3-424c-9b2f-95c26279e940, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:11,060 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17c0f6b5-cce3-424c-9b2f-95c26279e940, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:11,062 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17c0f6b5-cce3-424c-9b2f-95c26279e940, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:11,399 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:11,400 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:11,401 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f11e099e-58c2-4974-99d5-20b01a4cefd5, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:11,402 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f11e099e-58c2-4974-99d5-20b01a4cefd5, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:11,404 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f11e099e-58c2-4974-99d5-20b01a4cefd5, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:12,045 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:12,046 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-10-31 14:37:12,046 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:12,047 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97ea43a0-10f9-424a-8b6d-1d6c818ab75a, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:12,048 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97ea43a0-10f9-424a-8b6d-1d6c818ab75a, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:12,050 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97ea43a0-10f9-424a-8b6d-1d6c818ab75a, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:16,295 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:16,296 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:16,297 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5f43999-8382-4c4f-a5ce-b4955d8f5340, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:16,302 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5f43999-8382-4c4f-a5ce-b4955d8f5340, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:16,305 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5f43999-8382-4c4f-a5ce-b4955d8f5340, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:16,631 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:16,632 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:16,633 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d10eda30-a35f-42b9-800d-67a7bacbf454, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:16,634 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d10eda30-a35f-42b9-800d-67a7bacbf454, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:16,635 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d10eda30-a35f-42b9-800d-67a7bacbf454, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:17,255 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:17,256 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-10-31 14:37:17,256 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:17,257 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 799cdf52-957b-430d-9d19-670b32ae0adc, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:17,258 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 799cdf52-957b-430d-9d19-670b32ae0adc, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:17,260 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 799cdf52-957b-430d-9d19-670b32ae0adc, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:21,548 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:21,549 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:21,550 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fac30067-c1aa-4ae7-9616-7f945801c536, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:21,551 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fac30067-c1aa-4ae7-9616-7f945801c536, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:21,552 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fac30067-c1aa-4ae7-9616-7f945801c536, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:21,912 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:21,913 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:21,914 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b25e818-07bb-44a7-9332-1585f02e72a5, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:21,915 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b25e818-07bb-44a7-9332-1585f02e72a5, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:21,916 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b25e818-07bb-44a7-9332-1585f02e72a5, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:22,524 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:22,525 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-10-31 14:37:22,525 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:22,526 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5b3b05a-aa33-411c-967a-2583f661b574, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:22,527 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5b3b05a-aa33-411c-967a-2583f661b574, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:22,529 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5b3b05a-aa33-411c-967a-2583f661b574, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:27,074 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:27,075 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:27,076 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e87b898c-20ee-4385-8aa6-2dcb5e3ea3a4, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:27,077 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e87b898c-20ee-4385-8aa6-2dcb5e3ea3a4, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:27,078 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e87b898c-20ee-4385-8aa6-2dcb5e3ea3a4, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:27,345 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:27,346 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:27,347 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 81ef7a80-dfa0-43e4-8fbf-70ff625b194a, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:27,348 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 81ef7a80-dfa0-43e4-8fbf-70ff625b194a, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:27,349 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 81ef7a80-dfa0-43e4-8fbf-70ff625b194a, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:28,127 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:28,128 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-10-31 14:37:28,128 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:28,129 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7fe48964-c09e-43dd-be3c-f14db7f850b4, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:28,130 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7fe48964-c09e-43dd-be3c-f14db7f850b4, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:28,137 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7fe48964-c09e-43dd-be3c-f14db7f850b4, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:33,162 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:33,163 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:33,163 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0d934ed4-7573-4b8e-87ce-4ab155ebaaac, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:33,164 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0d934ed4-7573-4b8e-87ce-4ab155ebaaac, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:33,166 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0d934ed4-7573-4b8e-87ce-4ab155ebaaac, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:33,477 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:33,478 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:33,479 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 78f2ddde-3bb5-4b6b-98b6-7241ffe32eb1, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:33,480 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 78f2ddde-3bb5-4b6b-98b6-7241ffe32eb1, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:33,481 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 78f2ddde-3bb5-4b6b-98b6-7241ffe32eb1, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:34,107 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:34,108 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-10-31 14:37:34,108 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:34,109 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dfec2d52-7b8d-4311-b2af-70cbf952fd9c, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:34,110 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dfec2d52-7b8d-4311-b2af-70cbf952fd9c, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:34,112 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dfec2d52-7b8d-4311-b2af-70cbf952fd9c, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:35,073 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:35,074 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:35,075 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b111f715-13f7-4735-a641-43e464ec9018, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:35,076 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b111f715-13f7-4735-a641-43e464ec9018, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:35,077 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b111f715-13f7-4735-a641-43e464ec9018, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:35,321 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:35,322 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:35,323 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fd187aad-a7f1-4a86-85fd-4881e5751dd2, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:35,323 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fd187aad-a7f1-4a86-85fd-4881e5751dd2, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:35,325 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fd187aad-a7f1-4a86-85fd-4881e5751dd2, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:35,569 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:35,570 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:35,571 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a5c49740-5af7-42df-9985-b3ae9c80b496, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:35,572 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a5c49740-5af7-42df-9985-b3ae9c80b496, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:35,574 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a5c49740-5af7-42df-9985-b3ae9c80b496, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:39,009 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:49,091 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:49,092 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2a30f163-c8be-44ba-88d3-33b7ac8c35b4, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:49,093 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2a30f163-c8be-44ba-88d3-33b7ac8c35b4, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:49,095 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2a30f163-c8be-44ba-88d3-33b7ac8c35b4, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:52,460 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:52,502 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1595704)
2025-10-31 14:37:52,502 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:52,503 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1790d245-f4a9-430e-b52e-a242c1893c08, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:52,504 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1790d245-f4a9-430e-b52e-a242c1893c08, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:52,505 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1790d245-f4a9-430e-b52e-a242c1893c08, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:52,795 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:52,795 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:37:52,796 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 732cef71-e402-4cea-8c7a-aceeea6ddd58, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:37:52,797 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 732cef71-e402-4cea-8c7a-aceeea6ddd58, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:37:52,799 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 732cef71-e402-4cea-8c7a-aceeea6ddd58, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:37:56,090 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:38:06,130 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:38:06,131 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 80feb954-112c-4803-80e4-05d4c03d9d63, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:38:06,132 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 80feb954-112c-4803-80e4-05d4c03d9d63, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:38:06,133 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 80feb954-112c-4803-80e4-05d4c03d9d63, ghrun-uk32wjuvr4.auto.internal:11858): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:38:09,459 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=
2025-10-31 14:38:09,495 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1595704)
2025-10-31 14:38:09,495 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_19_compression` 8092160 / 1595704: 5.071216215538722
2025-10-31 14:38:09,498 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 14:38:09,499 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 0f5166c2-2269-4a8a-9285-82dfdb1517a1, ghrun-uk32wjuvr4.auto.internal:11858): creating call state
2025-10-31 14:38:09,500 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 0f5166c2-2269-4a8a-9285-82dfdb1517a1, ghrun-uk32wjuvr4.auto.internal:11858): request = { session_id: "ydb://session/3?node_id=1&id=YTlmZDFlYzMtODlhY2Q0ZDgtNWE4N2QzMDctNGU1YTYyNTA=" }
2025-10-31 14:38:09,503 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 0f5166c2-2269-4a8a-9285-82dfdb1517a1, ghrun-uk32wjuvr4.auto.internal:11858): response = { status: SUCCESS }
2025-10-31 14:38:09,505 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-31 14:38:09,506 - DEBUG - ydb.topic - close: Close topic client
2025-10-31 14:38:09,506 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-31 14:38:09,507 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 3e3faf19-a063-4914-99c9-6ffb6f9cfb79, ghrun-uk32wjuvr4.auto.internal:11858): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-10-31 14:38:09,508 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:11858
2025-10-31 14:38:09,508 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-uk32wjuvr4.auto.internal:11858
2025-10-31 14:38:09,513 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-31 14:38:21,521 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:11858/1
2025-10-31 14:38:21,524 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10510
2025-10-31 14:38:21,525 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24583
2025-10-31 14:38:21,526 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4335
2025-10-31 14:38:21,526 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11858
2025-10-31 14:38:21,526 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9644
