2025-10-16 17:21:28,697 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:21:28,697 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_17_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 17]
2025-10-16 17:21:28,698 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:21:28,698 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 17:21:28,701 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_17_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 17])
2025-10-16 17:21:28,701 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:28,702 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 573f27e0-b8a5-4ca8-8d56-e562df7127c6, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:28,703 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 573f27e0-b8a5-4ca8-8d56-e562df7127c6, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_17_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 17)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:28,705 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 573f27e0-b8a5-4ca8-8d56-e562df7127c6, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:33,009 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:33,009 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_17_compression created
2025-10-16 17:21:33,010 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:33,011 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f52a2326-236d-4b20-934d-7f8d36cfb9af, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:33,011 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f52a2326-236d-4b20-934d-7f8d36cfb9af, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_17_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:33,013 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f52a2326-236d-4b20-934d-7f8d36cfb9af, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:34,285 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:34,286 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:34,286 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b3aa722b-1d3a-464e-99b4-f290d2601dd4, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:34,287 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b3aa722b-1d3a-464e-99b4-f290d2601dd4, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:34,342 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b3aa722b-1d3a-464e-99b4-f290d2601dd4, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:39,969 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:39,969 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:39,970 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 967b744e-cb18-4072-b1fb-4c614a775bee, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:39,971 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 967b744e-cb18-4072-b1fb-4c614a775bee, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:39,972 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 967b744e-cb18-4072-b1fb-4c614a775bee, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:41,326 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:41,327 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:41,328 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8532bc25-052c-40bd-946a-80bfed7abe3b, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:41,329 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8532bc25-052c-40bd-946a-80bfed7abe3b, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:41,330 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8532bc25-052c-40bd-946a-80bfed7abe3b, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:43,624 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:43,625 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-10-16 17:21:43,625 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:43,626 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b760010e-cc80-4e04-881c-2b4d5a5020b5, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:43,627 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b760010e-cc80-4e04-881c-2b4d5a5020b5, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:43,629 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b760010e-cc80-4e04-881c-2b4d5a5020b5, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:49,093 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:49,093 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:49,094 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8ec6278e-8990-430f-98c2-839621cb79e3, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:49,095 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8ec6278e-8990-430f-98c2-839621cb79e3, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:49,096 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8ec6278e-8990-430f-98c2-839621cb79e3, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:49,685 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:49,686 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:49,686 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7e19a1fc-d6c7-4646-ab69-3ad85bb935cc, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:49,687 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7e19a1fc-d6c7-4646-ab69-3ad85bb935cc, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:49,690 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7e19a1fc-d6c7-4646-ab69-3ad85bb935cc, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:50,388 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:50,389 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-10-16 17:21:50,389 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:50,390 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d13f4940-e3f9-43f7-9ac6-e51d1b9a2237, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:50,391 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d13f4940-e3f9-43f7-9ac6-e51d1b9a2237, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:50,396 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d13f4940-e3f9-43f7-9ac6-e51d1b9a2237, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:56,447 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:56,447 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:56,448 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e7f2d572-6890-49a4-8357-074440b28599, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:56,449 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e7f2d572-6890-49a4-8357-074440b28599, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:56,450 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e7f2d572-6890-49a4-8357-074440b28599, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:57,062 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:57,062 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:57,063 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1a2daf24-2b9d-4932-a24c-77e7c4ae9739, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:57,064 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1a2daf24-2b9d-4932-a24c-77e7c4ae9739, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:57,065 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1a2daf24-2b9d-4932-a24c-77e7c4ae9739, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:57,823 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:57,824 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 987568, 'BlobCount': 396}}
2025-10-16 17:21:57,824 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:21:57,825 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9bb652b3-8cf0-454f-bab0-60298a3f2a24, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:21:57,826 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9bb652b3-8cf0-454f-bab0-60298a3f2a24, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:57,827 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9bb652b3-8cf0-454f-bab0-60298a3f2a24, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:03,336 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:03,337 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:03,338 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0956aa52-7afd-4776-a2f8-485b7c9a38c0, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:03,339 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0956aa52-7afd-4776-a2f8-485b7c9a38c0, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:03,345 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0956aa52-7afd-4776-a2f8-485b7c9a38c0, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:03,777 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:03,778 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:03,779 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a62b13dc-8663-4994-a330-565b36c778b1, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:03,780 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a62b13dc-8663-4994-a330-565b36c778b1, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:03,781 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a62b13dc-8663-4994-a330-565b36c778b1, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:04,257 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:04,258 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 220}}, blobs: {'__DEFAULT': {'Portions': 220, 'BlobSize': 1280656, 'BlobCount': 494}}
2025-10-16 17:22:04,258 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:04,259 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f5cd6112-c6ec-49af-9bac-d134f217a081, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:04,260 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f5cd6112-c6ec-49af-9bac-d134f217a081, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:04,261 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f5cd6112-c6ec-49af-9bac-d134f217a081, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:08,935 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:08,936 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:08,936 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 265e0185-c7f6-498b-8b9c-55ea30fbf7c7, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:08,937 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 265e0185-c7f6-498b-8b9c-55ea30fbf7c7, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:08,939 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 265e0185-c7f6-498b-8b9c-55ea30fbf7c7, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:09,202 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:09,203 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:09,203 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, de8b5e4c-098a-4137-8c71-647433b93e0a, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:09,204 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, de8b5e4c-098a-4137-8c71-647433b93e0a, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:09,206 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, de8b5e4c-098a-4137-8c71-647433b93e0a, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:09,716 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:09,717 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 260}}, blobs: {'__DEFAULT': {'Portions': 260, 'BlobSize': 1609256, 'BlobCount': 590}}
2025-10-16 17:22:09,717 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:09,718 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97653329-1151-47f6-aaca-60854e135c28, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:09,719 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97653329-1151-47f6-aaca-60854e135c28, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:09,721 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97653329-1151-47f6-aaca-60854e135c28, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:14,239 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:14,240 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:14,241 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f4aca7ab-b323-420b-8b6c-f44516eb3854, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:14,242 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f4aca7ab-b323-420b-8b6c-f44516eb3854, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:14,249 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f4aca7ab-b323-420b-8b6c-f44516eb3854, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:14,650 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:14,651 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:14,652 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bc118df5-bf27-4c9e-af97-4551f8640088, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:14,653 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bc118df5-bf27-4c9e-af97-4551f8640088, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:14,654 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bc118df5-bf27-4c9e-af97-4551f8640088, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:15,193 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:15,194 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 292}}, blobs: {'__DEFAULT': {'Portions': 292, 'BlobSize': 1936488, 'BlobCount': 670}}
2025-10-16 17:22:15,194 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:15,195 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3fa50fd1-aec3-47e5-97e4-a3f3dcfb34db, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:15,196 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3fa50fd1-aec3-47e5-97e4-a3f3dcfb34db, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:15,198 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3fa50fd1-aec3-47e5-97e4-a3f3dcfb34db, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:19,605 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:19,606 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:19,606 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d5f81241-73c3-431f-bafa-f83f5ecc2f09, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:19,607 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d5f81241-73c3-431f-bafa-f83f5ecc2f09, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:19,609 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d5f81241-73c3-431f-bafa-f83f5ecc2f09, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:19,873 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:19,874 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:19,875 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8608b663-006b-40af-8ce4-357091892bc7, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:19,876 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8608b663-006b-40af-8ce4-357091892bc7, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:19,877 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8608b663-006b-40af-8ce4-357091892bc7, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:20,453 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:20,454 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 341}}, blobs: {'__DEFAULT': {'Portions': 341, 'BlobSize': 2255120, 'BlobCount': 774}}
2025-10-16 17:22:20,455 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:20,455 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8eae0cb7-7578-41f0-a43e-f730ce0b1b70, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:20,457 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8eae0cb7-7578-41f0-a43e-f730ce0b1b70, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:20,459 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8eae0cb7-7578-41f0-a43e-f730ce0b1b70, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:24,677 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:24,677 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:24,678 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, abe98655-1c13-43d0-abc5-b7633f487ef3, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:24,679 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, abe98655-1c13-43d0-abc5-b7633f487ef3, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:24,681 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, abe98655-1c13-43d0-abc5-b7633f487ef3, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:24,941 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:24,942 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:24,943 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b792889b-1ee0-49b6-90a6-dd3b7e274683, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:24,944 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b792889b-1ee0-49b6-90a6-dd3b7e274683, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:24,946 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b792889b-1ee0-49b6-90a6-dd3b7e274683, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:25,477 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:25,478 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 351}}, blobs: {'__DEFAULT': {'Portions': 351, 'BlobSize': 2570192, 'BlobCount': 812}}
2025-10-16 17:22:25,478 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:25,479 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9944c6e-48f2-4b26-8de4-e2f3b5d41cc5, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:25,480 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9944c6e-48f2-4b26-8de4-e2f3b5d41cc5, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:25,482 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9944c6e-48f2-4b26-8de4-e2f3b5d41cc5, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:30,022 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:30,023 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:30,024 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b77c617d-06f9-4bd0-8bf6-1fac0a1a4efb, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:30,025 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b77c617d-06f9-4bd0-8bf6-1fac0a1a4efb, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:30,027 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b77c617d-06f9-4bd0-8bf6-1fac0a1a4efb, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:30,303 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:30,304 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:30,305 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61cdd2e0-805c-484b-9eeb-f6a300998000, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:30,306 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61cdd2e0-805c-484b-9eeb-f6a300998000, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:30,310 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61cdd2e0-805c-484b-9eeb-f6a300998000, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:30,841 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:30,842 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 394}}, blobs: {'__DEFAULT': {'Portions': 394, 'BlobSize': 2886456, 'BlobCount': 904}}
2025-10-16 17:22:30,842 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:30,843 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f7c73a94-e9a6-4102-b5c4-4d16c3df51cb, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:30,845 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f7c73a94-e9a6-4102-b5c4-4d16c3df51cb, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:30,847 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f7c73a94-e9a6-4102-b5c4-4d16c3df51cb, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:35,664 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:35,665 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:35,666 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6b1e7b3-4cff-451c-98c5-c1e7c5569ff4, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:35,667 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6b1e7b3-4cff-451c-98c5-c1e7c5569ff4, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:35,668 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6b1e7b3-4cff-451c-98c5-c1e7c5569ff4, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:35,965 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:35,966 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:35,967 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 39886da6-66d5-493b-819b-b8647e0d9f17, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:35,968 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 39886da6-66d5-493b-819b-b8647e0d9f17, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:35,969 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 39886da6-66d5-493b-819b-b8647e0d9f17, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:36,454 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:36,455 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 410}}, blobs: {'__DEFAULT': {'Portions': 410, 'BlobSize': 3198464, 'BlobCount': 948}}
2025-10-16 17:22:36,455 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:36,456 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c6484ec-4f17-4ac3-a5dc-858f46bccb51, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:36,457 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c6484ec-4f17-4ac3-a5dc-858f46bccb51, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_17_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:36,458 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c6484ec-4f17-4ac3-a5dc-858f46bccb51, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:36,705 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:22:36,706 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:22:36,741 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:22:36,742 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 32a1c750-fece-47b6-bef3-397348310437, localhost:13835): creating call state
2025-10-16 17:22:36,742 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 32a1c750-fece-47b6-bef3-397348310437, localhost:13835): request = { database: "/Root" }
2025-10-16 17:22:36,789 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 32a1c750-fece-47b6-bef3-397348310437, localhost:13835): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 13835 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-16 17:22:36,790 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:13835, location 1, ssl: False>]>
2025-10-16 17:22:36,791 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:13835
2025-10-16 17:22:37,464 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:37,465 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:37,466 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 23b650fa-b3c5-4b96-b99d-fbf99f16f28c, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:37,467 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 23b650fa-b3c5-4b96-b99d-fbf99f16f28c, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:37,471 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 23b650fa-b3c5-4b96-b99d-fbf99f16f28c, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:37,830 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:37,831 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:37,832 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a19efeb9-5595-473e-ac3e-e5b20e6a646b, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:37,833 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a19efeb9-5595-473e-ac3e-e5b20e6a646b, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:37,839 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a19efeb9-5595-473e-ac3e-e5b20e6a646b, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:38,249 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:38,250 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:38,251 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 46ac53ff-f11a-45d6-8325-d019cf34b9d6, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:38,252 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 46ac53ff-f11a-45d6-8325-d019cf34b9d6, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:38,254 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 46ac53ff-f11a-45d6-8325-d019cf34b9d6, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:41,225 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:51,253 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:51,254 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8fb1674e-a6c1-45ed-903e-e4d33926413f, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:51,255 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8fb1674e-a6c1-45ed-903e-e4d33926413f, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:51,259 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8fb1674e-a6c1-45ed-903e-e4d33926413f, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:53,827 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:53,857 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_17_compression`, volumes `value` (8000000, 1569648)
2025-10-16 17:22:53,858 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:53,858 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2355f6c5-3050-42ce-b702-7d42cda4dc95, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:53,859 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2355f6c5-3050-42ce-b702-7d42cda4dc95, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" 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_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:53,861 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2355f6c5-3050-42ce-b702-7d42cda4dc95, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:54,217 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:54,217 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:22:54,218 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d49de7fd-aa81-4e1a-8eeb-d40c3ee27ba5, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:22:54,219 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d49de7fd-aa81-4e1a-8eeb-d40c3ee27ba5, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:54,229 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d49de7fd-aa81-4e1a-8eeb-d40c3ee27ba5, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:56,774 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:23:06,802 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:23:06,803 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1112c253-16c5-4377-8121-879ec3a55a49, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:23:06,804 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1112c253-16c5-4377-8121-879ec3a55a49, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:06,806 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1112c253-16c5-4377-8121-879ec3a55a49, ghrun-2kszfvc6cm.auto.internal:13835): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:09,451 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=
2025-10-16 17:23:09,482 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_17_compression`, volumes `value` (8000000, 1569648)
2025-10-16 17:23:09,483 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_17_compression` 8034704 / 1569648: 5.118793512940481
2025-10-16 17:23:09,485 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-16 17:23:09,486 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ec4ff383-42ea-458c-bb9e-708df06120eb, ghrun-2kszfvc6cm.auto.internal:13835): creating call state
2025-10-16 17:23:09,487 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ec4ff383-42ea-458c-bb9e-708df06120eb, ghrun-2kszfvc6cm.auto.internal:13835): request = { session_id: "ydb://session/3?node_id=1&id=ZWQzOGJhODUtMzYxZjZlN2MtOTU4ZGFiMGUtY2VlNTkxNGI=" }
2025-10-16 17:23:09,491 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ec4ff383-42ea-458c-bb9e-708df06120eb, ghrun-2kszfvc6cm.auto.internal:13835): response = { status: SUCCESS }
2025-10-16 17:23:09,493 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-16 17:23:09,494 - DEBUG - ydb.topic - close: Close topic client
2025-10-16 17:23:09,494 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-16 17:23:09,494 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:13835
2025-10-16 17:23:09,495 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 770add57-b6cf-45d5-91cd-fc8073727ce0, ghrun-2kszfvc6cm.auto.internal:13835): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-10-16 17:23:09,496 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-2kszfvc6cm.auto.internal:13835
2025-10-16 17:23:09,502 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-16 17:23:21,520 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:13835/1
2025-10-16 17:23:21,524 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26625
2025-10-16 17:23:21,525 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27015
2025-10-16 17:23:21,532 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1242
2025-10-16 17:23:21,532 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13835
2025-10-16 17:23:21,545 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28774
