2025-11-05 12:25:57,017 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:25:57,018 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_21_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 21]
2025-11-05 12:25:57,018 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:25:57,023 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-05 12:25:57,028 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_21_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 21])
2025-11-05 12:25:57,029 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:25:57,029 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8decb3f9-27af-48b0-85db-a69e0f62303c, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:25:57,030 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8decb3f9-27af-48b0-85db-a69e0f62303c, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_21_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 21)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:57,044 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8decb3f9-27af-48b0-85db-a69e0f62303c, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:02,219 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:02,219 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_21_compression created
2025-11-05 12:26:02,220 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:02,220 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b55fd79f-1a1c-4867-9982-ba012988998b, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:02,222 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b55fd79f-1a1c-4867-9982-ba012988998b, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_21_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:02,223 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b55fd79f-1a1c-4867-9982-ba012988998b, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:04,098 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:04,099 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:04,099 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 980b5601-fcb6-4908-9672-513c155e3832, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:04,100 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 980b5601-fcb6-4908-9672-513c155e3832, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:04,102 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 980b5601-fcb6-4908-9672-513c155e3832, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:10,359 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:10,360 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:10,360 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b1a1b0e1-d0e8-4ac3-be2a-45f2749b63b7, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:10,361 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b1a1b0e1-d0e8-4ac3-be2a-45f2749b63b7, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:10,362 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b1a1b0e1-d0e8-4ac3-be2a-45f2749b63b7, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:11,827 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:11,828 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:11,829 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 23f999d6-1305-4df1-ac1b-0565fa0b0e26, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:11,830 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 23f999d6-1305-4df1-ac1b-0565fa0b0e26, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:11,831 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 23f999d6-1305-4df1-ac1b-0565fa0b0e26, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:14,435 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:14,436 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-05 12:26:14,436 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:14,437 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9a6693ce-e82e-43ac-a51d-8a8fc7045c3a, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:14,438 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9a6693ce-e82e-43ac-a51d-8a8fc7045c3a, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:14,446 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9a6693ce-e82e-43ac-a51d-8a8fc7045c3a, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:20,606 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:20,606 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:20,607 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d4edf38-7ebb-49bf-ad03-2b928c4c0e61, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:20,608 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d4edf38-7ebb-49bf-ad03-2b928c4c0e61, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:20,609 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d4edf38-7ebb-49bf-ad03-2b928c4c0e61, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:20,972 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:20,973 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:20,973 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f92ba95f-e575-43de-834e-454b5d2e160b, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:20,974 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f92ba95f-e575-43de-834e-454b5d2e160b, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:20,976 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f92ba95f-e575-43de-834e-454b5d2e160b, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:22,112 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:22,113 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-05 12:26:22,113 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:22,114 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 691a85cf-5dda-4d21-9ec9-f0ae26bf1998, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:22,115 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 691a85cf-5dda-4d21-9ec9-f0ae26bf1998, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:22,116 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 691a85cf-5dda-4d21-9ec9-f0ae26bf1998, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:28,554 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:28,555 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:28,556 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b903ff8-6c5c-4203-930d-91743d853abe, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:28,557 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b903ff8-6c5c-4203-930d-91743d853abe, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:28,558 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b903ff8-6c5c-4203-930d-91743d853abe, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:29,062 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:29,062 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:29,063 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d73c250-6f51-40e1-b23f-c5371714d919, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:29,064 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d73c250-6f51-40e1-b23f-c5371714d919, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:29,071 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d73c250-6f51-40e1-b23f-c5371714d919, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:30,025 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:30,025 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-05 12:26:30,026 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:30,027 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e3c1f4a2-1bc2-4dc8-888f-d6c01ab3c875, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:30,028 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e3c1f4a2-1bc2-4dc8-888f-d6c01ab3c875, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:30,039 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e3c1f4a2-1bc2-4dc8-888f-d6c01ab3c875, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:36,803 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:36,803 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:36,804 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a57f8d7f-3909-4bff-a8e0-239545992f0a, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:36,805 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a57f8d7f-3909-4bff-a8e0-239545992f0a, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:36,815 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a57f8d7f-3909-4bff-a8e0-239545992f0a, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:37,275 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:37,276 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:37,277 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0dad12e9-fe3d-410d-9a2e-274918f1c97d, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:37,278 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0dad12e9-fe3d-410d-9a2e-274918f1c97d, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:37,281 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0dad12e9-fe3d-410d-9a2e-274918f1c97d, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:38,041 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:38,041 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-05 12:26:38,042 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:38,043 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17f1d722-90ce-4d12-b0d3-a550d0a36fda, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:38,044 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17f1d722-90ce-4d12-b0d3-a550d0a36fda, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:38,047 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17f1d722-90ce-4d12-b0d3-a550d0a36fda, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:42,373 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 12:26:42,374 - 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-11-05 12:26:42,413 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-05 12:26:42,414 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, d288a931-a12b-4370-a312-d2c9fe2e69a0, localhost:18712): creating call state
2025-11-05 12:26:42,415 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, d288a931-a12b-4370-a312-d2c9fe2e69a0, localhost:18712): request = { database: "/Root" }
2025-11-05 12:26:42,424 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, d288a931-a12b-4370-a312-d2c9fe2e69a0, localhost:18712): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-anwhr6cmha.auto.internal" port: 18712 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-11-05 12:26:42,425 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-anwhr6cmha.auto.internal:18712, location 1, ssl: False>]>
2025-11-05 12:26:42,425 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:18712
2025-11-05 12:26:45,359 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:45,360 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:45,360 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9fdee1e-9e04-431c-ad02-b6680986cdb1, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:45,361 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9fdee1e-9e04-431c-ad02-b6680986cdb1, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:45,368 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9fdee1e-9e04-431c-ad02-b6680986cdb1, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:45,936 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:45,937 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:45,937 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e9f1c8a-d1b7-4edd-bc20-9d71b5002681, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:45,938 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e9f1c8a-d1b7-4edd-bc20-9d71b5002681, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:45,941 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e9f1c8a-d1b7-4edd-bc20-9d71b5002681, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:47,151 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:47,152 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-05 12:26:47,152 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:47,153 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5866a49-87b6-4531-928b-7f721d3f876b, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:47,157 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5866a49-87b6-4531-928b-7f721d3f876b, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:47,159 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5866a49-87b6-4531-928b-7f721d3f876b, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:54,238 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:54,239 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:54,239 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd06b7b0-566d-49f6-9996-4dca3f2f4dce, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:54,240 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd06b7b0-566d-49f6-9996-4dca3f2f4dce, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:54,242 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd06b7b0-566d-49f6-9996-4dca3f2f4dce, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:54,624 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:54,625 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:54,625 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 86c255dd-f2bf-441a-8337-1af2dc5f99cb, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:54,627 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 86c255dd-f2bf-441a-8337-1af2dc5f99cb, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:54,628 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 86c255dd-f2bf-441a-8337-1af2dc5f99cb, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:55,935 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:55,936 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-05 12:26:55,936 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:26:55,937 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 824169e3-7fce-4871-aba0-08bbcbc2bbf4, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:26:55,938 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 824169e3-7fce-4871-aba0-08bbcbc2bbf4, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:55,950 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 824169e3-7fce-4871-aba0-08bbcbc2bbf4, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:02,370 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:02,371 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:02,372 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4f0911d8-3d36-4c69-8b41-b4830dfe47ad, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:02,373 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4f0911d8-3d36-4c69-8b41-b4830dfe47ad, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:02,374 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4f0911d8-3d36-4c69-8b41-b4830dfe47ad, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:02,823 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:02,824 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:02,825 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b1afa10-d5a8-42a4-8f05-bb81320d982a, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:02,834 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b1afa10-d5a8-42a4-8f05-bb81320d982a, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:02,835 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b1afa10-d5a8-42a4-8f05-bb81320d982a, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:03,791 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:03,792 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-05 12:27:03,792 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:03,793 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2f0f2e22-4b13-4152-bcd2-e8a13ca002ce, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:03,794 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2f0f2e22-4b13-4152-bcd2-e8a13ca002ce, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:03,796 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2f0f2e22-4b13-4152-bcd2-e8a13ca002ce, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:10,767 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:10,768 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:10,768 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec5281a4-41a7-4f00-9006-051c37158afb, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:10,769 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec5281a4-41a7-4f00-9006-051c37158afb, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:10,772 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec5281a4-41a7-4f00-9006-051c37158afb, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:11,128 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:11,128 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:11,129 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b1dfe59e-b15f-48b3-97bf-138b5aa3b117, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:11,130 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b1dfe59e-b15f-48b3-97bf-138b5aa3b117, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:11,131 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b1dfe59e-b15f-48b3-97bf-138b5aa3b117, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:12,058 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:12,059 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-05 12:27:12,060 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:12,060 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ebece012-8001-4332-8ce9-89084b3c8e34, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:12,061 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ebece012-8001-4332-8ce9-89084b3c8e34, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:12,063 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ebece012-8001-4332-8ce9-89084b3c8e34, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:17,229 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:17,230 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:17,231 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6ca68211-7ee4-44cd-a58f-e545dd2cf8bc, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:17,231 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6ca68211-7ee4-44cd-a58f-e545dd2cf8bc, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:17,233 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6ca68211-7ee4-44cd-a58f-e545dd2cf8bc, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:17,667 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:17,668 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:17,668 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a0ecbe8f-d4a4-4e08-b2bd-f18fbf82c6d2, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:17,669 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a0ecbe8f-d4a4-4e08-b2bd-f18fbf82c6d2, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:17,672 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a0ecbe8f-d4a4-4e08-b2bd-f18fbf82c6d2, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:18,465 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:18,466 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-05 12:27:18,466 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:18,467 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 42380535-7995-4c50-89c6-62c1df970512, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:18,468 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 42380535-7995-4c50-89c6-62c1df970512, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:18,469 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 42380535-7995-4c50-89c6-62c1df970512, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:23,332 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:23,333 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:23,334 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7e73f41f-9cc5-4818-82f8-65dc8e641e57, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:23,335 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7e73f41f-9cc5-4818-82f8-65dc8e641e57, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:23,336 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7e73f41f-9cc5-4818-82f8-65dc8e641e57, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:23,839 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:23,840 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:23,841 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c45d976-6256-400b-bbd3-95be532caafc, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:23,841 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c45d976-6256-400b-bbd3-95be532caafc, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:23,843 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c45d976-6256-400b-bbd3-95be532caafc, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:24,632 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:24,633 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-05 12:27:24,633 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:24,634 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61c2578c-3735-48d2-9de8-41769225ed2f, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:24,639 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61c2578c-3735-48d2-9de8-41769225ed2f, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_21_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:24,641 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61c2578c-3735-48d2-9de8-41769225ed2f, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:26,455 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:26,456 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:26,456 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e8d1959d-4cf6-48b6-bc2f-3905d2610346, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:26,457 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e8d1959d-4cf6-48b6-bc2f-3905d2610346, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:26,459 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e8d1959d-4cf6-48b6-bc2f-3905d2610346, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:27,037 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:27,038 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:27,039 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ba55af8-ac10-4986-a252-f762c1eac031, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:27,039 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ba55af8-ac10-4986-a252-f762c1eac031, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:27,041 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ba55af8-ac10-4986-a252-f762c1eac031, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:27,540 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:27,541 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:27,541 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 03a2b3b9-17c0-4fc5-bb93-1198a7e233fe, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:27,542 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 03a2b3b9-17c0-4fc5-bb93-1198a7e233fe, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:27,544 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 03a2b3b9-17c0-4fc5-bb93-1198a7e233fe, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:32,633 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:42,759 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:42,760 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e38ba3c5-dfc2-4f99-ace7-f4d80f9a3c6e, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:42,761 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e38ba3c5-dfc2-4f99-ace7-f4d80f9a3c6e, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:42,772 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e38ba3c5-dfc2-4f99-ace7-f4d80f9a3c6e, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:47,850 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:47,890 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_21_compression`, volumes `value` (8000000, 1595704)
2025-11-05 12:27:47,891 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:47,892 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cdfe88b2-1125-4b92-8d7e-4b0e2956cb2e, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:47,893 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cdfe88b2-1125-4b92-8d7e-4b0e2956cb2e, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" 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_21_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:47,895 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cdfe88b2-1125-4b92-8d7e-4b0e2956cb2e, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:48,312 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:48,313 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:27:48,314 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 406b5184-3c69-434e-90ad-da707a15656d, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:27:48,315 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 406b5184-3c69-434e-90ad-da707a15656d, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:48,316 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 406b5184-3c69-434e-90ad-da707a15656d, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:52,852 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:28:02,895 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:28:02,896 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2fd9ff8-2342-409f-a2d6-14a9f5a73e42, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:28:02,896 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2fd9ff8-2342-409f-a2d6-14a9f5a73e42, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:28:02,897 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2fd9ff8-2342-409f-a2d6-14a9f5a73e42, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:28:07,534 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:28:17,586 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:28:17,587 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 44ea5cd6-4971-4cdd-be28-92111f4e4a2b, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:28:17,588 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 44ea5cd6-4971-4cdd-be28-92111f4e4a2b, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:28:17,589 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 44ea5cd6-4971-4cdd-be28-92111f4e4a2b, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:28:20,507 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:28:30,542 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:28:30,543 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f195ca82-a810-47a4-888b-87e1e2ce2dea, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:28:30,544 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f195ca82-a810-47a4-888b-87e1e2ce2dea, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:28:30,546 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f195ca82-a810-47a4-888b-87e1e2ce2dea, ghrun-anwhr6cmha.auto.internal:18712): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:28:32,594 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=
2025-11-05 12:28:32,603 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_21_compression`, volumes `value` (8000000, 1492280)
2025-11-05 12:28:32,603 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_21_compression` 8092160 / 1492280: 5.422682070388935
2025-11-05 12:28:32,611 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-05 12:28:32,612 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 236c4162-88e6-4f15-8665-d0487fff1116, ghrun-anwhr6cmha.auto.internal:18712): creating call state
2025-11-05 12:28:32,613 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 236c4162-88e6-4f15-8665-d0487fff1116, ghrun-anwhr6cmha.auto.internal:18712): request = { session_id: "ydb://session/3?node_id=1&id=MTUzYTM0ZTYtNjBjNzVjMTMtZWM1YTFiNWItNDNlODkwOTg=" }
2025-11-05 12:28:32,625 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 236c4162-88e6-4f15-8665-d0487fff1116, ghrun-anwhr6cmha.auto.internal:18712): response = { status: SUCCESS }
2025-11-05 12:28:32,626 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-05 12:28:32,626 - DEBUG - ydb.topic - close: Close topic client
2025-11-05 12:28:32,627 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-05 12:28:32,627 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:18712
2025-11-05 12:28:32,628 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-anwhr6cmha.auto.internal:18712
2025-11-05 12:28:32,630 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-05 12:28:49,667 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:18712/1
2025-11-05 12:28:49,675 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14449
2025-11-05 12:28:49,676 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2156
2025-11-05 12:28:49,676 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13181
2025-11-05 12:28:49,676 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18712
2025-11-05 12:28:49,677 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14983
