2025-10-16 17:21:16,275 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:21:16,275 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10]
2025-10-16 17:21:16,276 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:21:16,276 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 17:21:16,278 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10])
2025-10-16 17:21:16,279 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:16,279 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 35d0cb5f-3bee-4023-8853-cab2e81971d1, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:16,280 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 35d0cb5f-3bee-4023-8853-cab2e81971d1, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_10_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 10)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:16,282 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 35d0cb5f-3bee-4023-8853-cab2e81971d1, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:19,877 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:19,877 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_10_compression created
2025-10-16 17:21:19,878 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:19,879 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e654e559-9f0b-4c4f-a338-d545f3581cf5, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:19,879 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e654e559-9f0b-4c4f-a338-d545f3581cf5, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_10_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:19,882 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e654e559-9f0b-4c4f-a338-d545f3581cf5, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:20,925 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:20,926 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:20,926 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 30fd90d7-7c86-4d75-bc40-5e90cba80aa2, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:20,928 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 30fd90d7-7c86-4d75-bc40-5e90cba80aa2, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:20,935 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 30fd90d7-7c86-4d75-bc40-5e90cba80aa2, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:23,429 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:23,430 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:23,431 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9e472f68-b743-4076-8f70-b104d233f89f, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:23,432 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9e472f68-b743-4076-8f70-b104d233f89f, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:23,433 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9e472f68-b743-4076-8f70-b104d233f89f, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:24,431 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:24,432 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:24,433 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 286d21a3-1ab1-43cc-99ff-55d023a9485a, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:24,434 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 286d21a3-1ab1-43cc-99ff-55d023a9485a, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_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:24,436 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 286d21a3-1ab1-43cc-99ff-55d023a9485a, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:26,358 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:26,359 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318048, 'BlobCount': 128}}
2025-10-16 17:21:26,359 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:26,360 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dbe4bec2-9a34-4b86-ac74-31136ca8f264, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:26,361 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dbe4bec2-9a34-4b86-ac74-31136ca8f264, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:26,362 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dbe4bec2-9a34-4b86-ac74-31136ca8f264, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:28,188 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:28,189 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:28,190 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4eda1931-cee7-406b-8b27-60a72c34a077, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:28,191 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4eda1931-cee7-406b-8b27-60a72c34a077, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:28,192 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4eda1931-cee7-406b-8b27-60a72c34a077, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:28,453 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:28,454 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:28,454 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ac51f08c-5b36-4dd5-95d0-2ad105ff3fa8, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:28,455 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ac51f08c-5b36-4dd5-95d0-2ad105ff3fa8, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_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:28,457 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ac51f08c-5b36-4dd5-95d0-2ad105ff3fa8, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:28,940 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:28,941 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637344, 'BlobCount': 256}}
2025-10-16 17:21:28,941 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:28,942 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 28f155ae-e588-48f4-b435-6642b492630a, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:28,943 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 28f155ae-e588-48f4-b435-6642b492630a, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:28,949 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 28f155ae-e588-48f4-b435-6642b492630a, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:31,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:31,137 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:31,138 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 60ea81bd-bda5-4307-8e9a-d12199ed711c, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:31,139 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 60ea81bd-bda5-4307-8e9a-d12199ed711c, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:31,142 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 60ea81bd-bda5-4307-8e9a-d12199ed711c, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:31,550 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:31,551 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:31,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dca153bd-5048-4748-84d8-b39fbc6a38b5, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:31,552 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dca153bd-5048-4748-84d8-b39fbc6a38b5, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_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:31,553 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dca153bd-5048-4748-84d8-b39fbc6a38b5, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:32,084 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:32,085 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 177}}, blobs: {'__DEFAULT': {'Portions': 177, 'BlobSize': 959080, 'BlobCount': 384}}
2025-10-16 17:21:32,085 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:32,086 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e758da5-11fb-4ff1-bc81-28474702c963, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:32,087 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e758da5-11fb-4ff1-bc81-28474702c963, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:32,100 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e758da5-11fb-4ff1-bc81-28474702c963, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:33,909 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:33,910 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:33,911 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 523de919-6081-47a3-8303-afd92cb71c2a, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:33,912 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 523de919-6081-47a3-8303-afd92cb71c2a, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:33,914 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 523de919-6081-47a3-8303-afd92cb71c2a, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:34,330 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:34,331 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:34,331 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 36a238f2-9320-48b0-9deb-e303ea618805, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:34,332 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 36a238f2-9320-48b0-9deb-e303ea618805, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_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:34,343 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 36a238f2-9320-48b0-9deb-e303ea618805, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:34,958 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:34,959 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 233}}, blobs: {'__DEFAULT': {'Portions': 233, 'BlobSize': 1277816, 'BlobCount': 504}}
2025-10-16 17:21:34,960 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:34,960 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 560c5106-fc49-45c8-a5be-e05e9e71e4e7, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:34,961 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 560c5106-fc49-45c8-a5be-e05e9e71e4e7, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:34,963 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 560c5106-fc49-45c8-a5be-e05e9e71e4e7, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:37,093 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:37,093 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:37,094 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 23a51a74-b401-4f69-85d0-319c9465fd77, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:37,095 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 23a51a74-b401-4f69-85d0-319c9465fd77, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:37,096 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 23a51a74-b401-4f69-85d0-319c9465fd77, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:37,413 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:37,414 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:37,415 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f15e8e04-3770-4512-82a3-30198883c334, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:37,416 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f15e8e04-3770-4512-82a3-30198883c334, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_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:37,417 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f15e8e04-3770-4512-82a3-30198883c334, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:37,989 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:37,989 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 291}}, blobs: {'__DEFAULT': {'Portions': 291, 'BlobSize': 1595760, 'BlobCount': 624}}
2025-10-16 17:21:37,990 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:37,990 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6c9a01cf-70e5-4b84-ada0-de773429d65b, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:37,991 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6c9a01cf-70e5-4b84-ada0-de773429d65b, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:37,994 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6c9a01cf-70e5-4b84-ada0-de773429d65b, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:39,685 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:39,686 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:39,686 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 67cd1725-5816-4be6-8a6e-54506ce2f2fc, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:39,687 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 67cd1725-5816-4be6-8a6e-54506ce2f2fc, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:39,689 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 67cd1725-5816-4be6-8a6e-54506ce2f2fc, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:39,965 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:39,966 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:39,967 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 110316b0-6230-4eaf-9cde-42971a6f777c, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:39,968 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 110316b0-6230-4eaf-9cde-42971a6f777c, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_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:39,970 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 110316b0-6230-4eaf-9cde-42971a6f777c, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:40,485 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:40,486 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 339}}, blobs: {'__DEFAULT': {'Portions': 339, 'BlobSize': 1911576, 'BlobCount': 728}}
2025-10-16 17:21:40,487 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:40,487 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db9e0fcb-0bc9-4e43-9dbe-2b2b63bd8519, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:40,489 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db9e0fcb-0bc9-4e43-9dbe-2b2b63bd8519, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:40,490 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db9e0fcb-0bc9-4e43-9dbe-2b2b63bd8519, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:41,076 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:21:41,077 - 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:21:41,091 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:21:41,091 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, d047d3ca-b382-45d6-8af0-8a7910df94b7, localhost:3963): creating call state
2025-10-16 17:21:41,092 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, d047d3ca-b382-45d6-8af0-8a7910df94b7, localhost:3963): request = { database: "/Root" }
2025-10-16 17:21:41,100 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, d047d3ca-b382-45d6-8af0-8a7910df94b7, localhost:3963): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 3963 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-16 17:21:41,101 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:3963, location 1, ssl: False>]>
2025-10-16 17:21:41,101 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:3963
2025-10-16 17:21:42,870 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:42,871 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:42,872 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db4a5404-0bde-4fc9-b339-7cc4d303e949, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:42,873 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db4a5404-0bde-4fc9-b339-7cc4d303e949, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:42,874 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db4a5404-0bde-4fc9-b339-7cc4d303e949, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:43,234 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:43,234 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:43,235 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 74569b32-6ab9-4ec9-98c4-0046bb718754, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:43,237 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 74569b32-6ab9-4ec9-98c4-0046bb718754, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_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:43,238 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 74569b32-6ab9-4ec9-98c4-0046bb718754, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:43,786 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:43,787 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 388}}, blobs: {'__DEFAULT': {'Portions': 388, 'BlobSize': 2227280, 'BlobCount': 832}}
2025-10-16 17:21:43,788 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:43,789 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bb8d04bb-a93f-4044-b521-61deccc37aaf, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:43,790 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bb8d04bb-a93f-4044-b521-61deccc37aaf, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:43,791 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bb8d04bb-a93f-4044-b521-61deccc37aaf, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:45,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:45,765 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:45,766 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8c1d7f39-ea34-4ab1-a148-6dcf89b3fb01, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:45,767 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8c1d7f39-ea34-4ab1-a148-6dcf89b3fb01, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:45,768 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8c1d7f39-ea34-4ab1-a148-6dcf89b3fb01, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:46,225 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:46,226 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:46,227 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e6e379b6-b27d-4d0c-b655-0c6cee8ffdf1, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:46,228 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e6e379b6-b27d-4d0c-b655-0c6cee8ffdf1, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_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:46,230 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e6e379b6-b27d-4d0c-b655-0c6cee8ffdf1, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:47,235 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:47,235 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 440}}, blobs: {'__DEFAULT': {'Portions': 440, 'BlobSize': 2543776, 'BlobCount': 940}}
2025-10-16 17:21:47,236 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:47,236 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0c17e712-adb1-4157-9e6d-577ab79de2b0, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:47,237 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0c17e712-adb1-4157-9e6d-577ab79de2b0, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:47,238 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0c17e712-adb1-4157-9e6d-577ab79de2b0, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:49,492 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:49,493 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:49,493 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5afe3f7c-7332-4fa3-912b-56cfbc57ef9b, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:49,494 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5afe3f7c-7332-4fa3-912b-56cfbc57ef9b, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:49,496 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5afe3f7c-7332-4fa3-912b-56cfbc57ef9b, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:49,850 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:49,850 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:49,851 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 339bd3b4-c2b8-4ad2-9b15-82b3fbb69235, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:49,852 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 339bd3b4-c2b8-4ad2-9b15-82b3fbb69235, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_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,854 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 339bd3b4-c2b8-4ad2-9b15-82b3fbb69235, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:50,535 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:50,536 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 497}}, blobs: {'__DEFAULT': {'Portions': 497, 'BlobSize': 2860920, 'BlobCount': 1056}}
2025-10-16 17:21:50,537 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:50,537 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b1227ba6-fb0d-4875-ad5e-5c0e503fe7be, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:50,538 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b1227ba6-fb0d-4875-ad5e-5c0e503fe7be, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:50,542 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b1227ba6-fb0d-4875-ad5e-5c0e503fe7be, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:52,341 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:52,342 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:52,343 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8317d6ce-5d9c-4697-b6ed-d075ab6f1b3f, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:52,344 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8317d6ce-5d9c-4697-b6ed-d075ab6f1b3f, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:52,345 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8317d6ce-5d9c-4697-b6ed-d075ab6f1b3f, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:52,739 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:52,740 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:52,741 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7883c46a-a4fb-46b7-936d-4d4db0ea56fe, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:52,742 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7883c46a-a4fb-46b7-936d-4d4db0ea56fe, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_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:52,745 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7883c46a-a4fb-46b7-936d-4d4db0ea56fe, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:53,643 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:53,644 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 553}}, blobs: {'__DEFAULT': {'Portions': 553, 'BlobSize': 3178208, 'BlobCount': 1170}}
2025-10-16 17:21:53,645 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:53,645 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 682ba519-46cb-490c-b5ae-666c6720897a, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:53,646 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 682ba519-46cb-490c-b5ae-666c6720897a, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_10_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:53,648 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 682ba519-46cb-490c-b5ae-666c6720897a, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:55,190 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:55,191 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:55,192 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8a7e3a2c-f575-4633-a83a-09faa70c9f58, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:55,193 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8a7e3a2c-f575-4633-a83a-09faa70c9f58, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:55,201 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8a7e3a2c-f575-4633-a83a-09faa70c9f58, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:55,601 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:55,602 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:55,603 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a6128bc-04a4-4f93-9b51-19dca668aaa5, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:55,604 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a6128bc-04a4-4f93-9b51-19dca668aaa5, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:55,606 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a6128bc-04a4-4f93-9b51-19dca668aaa5, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:55,994 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:55,995 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:55,996 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d23027b-b461-4b0d-b582-3b68c1f1d3b9, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:55,997 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d23027b-b461-4b0d-b582-3b68c1f1d3b9, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:55,998 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d23027b-b461-4b0d-b582-3b68c1f1d3b9, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:00,660 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:10,734 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:10,735 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b66a2a8a-8df2-4d94-9eba-90f3a16a9029, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:10,736 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b66a2a8a-8df2-4d94-9eba-90f3a16a9029, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:10,737 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b66a2a8a-8df2-4d94-9eba-90f3a16a9029, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:13,995 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:14,032 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1575960)
2025-10-16 17:22:14,033 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:14,034 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 227f1c4d-9457-4a68-bddf-1acd5dd63bbe, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:14,035 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 227f1c4d-9457-4a68-bddf-1acd5dd63bbe, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:14,036 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 227f1c4d-9457-4a68-bddf-1acd5dd63bbe, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:14,397 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:14,398 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:14,399 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d65f672-fca6-4971-9668-12cb7a969836, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:14,399 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d65f672-fca6-4971-9668-12cb7a969836, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:14,401 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d65f672-fca6-4971-9668-12cb7a969836, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:18,138 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:28,174 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:28,175 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd4f31bc-7971-4027-9e9c-087e005b2f77, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:22:28,176 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd4f31bc-7971-4027-9e9c-087e005b2f77, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:28,178 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd4f31bc-7971-4027-9e9c-087e005b2f77, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:29,700 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:22:29,717 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1510864)
2025-10-16 17:22:29,718 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_10_compression` 8077040 / 1510864: 5.345974224020163
2025-10-16 17:22:29,720 - INFO - ya.test - pytest_runtest_teardown: Test teardown
