2025-11-05 12:25:12,170 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:25:12,170 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_3_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 3]
2025-11-05 12:25:12,171 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:25:12,171 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-05 12:25:12,173 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_3_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 3])
2025-11-05 12:25:12,176 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:12,177 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 19558a8e-043f-475b-b0d8-66a029f15394, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:12,178 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 19558a8e-043f-475b-b0d8-66a029f15394, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_3_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 3)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:12,179 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 19558a8e-043f-475b-b0d8-66a029f15394, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:17,936 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:17,937 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_3_compression created
2025-11-05 12:25:17,937 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:17,938 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 88a3d4cc-93c3-4f87-a8a3-b6520a608ad9, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:17,939 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 88a3d4cc-93c3-4f87-a8a3-b6520a608ad9, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_3_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:17,940 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 88a3d4cc-93c3-4f87-a8a3-b6520a608ad9, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:19,427 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:19,428 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:19,428 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 63c044d5-b56f-4607-a2e0-e4f58e1bf358, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:19,430 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 63c044d5-b56f-4607-a2e0-e4f58e1bf358, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:19,435 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 63c044d5-b56f-4607-a2e0-e4f58e1bf358, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:21,327 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:21,327 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:21,328 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a9aa4840-7ccf-4589-9923-f633a98be8fe, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:21,329 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a9aa4840-7ccf-4589-9923-f633a98be8fe, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:21,332 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a9aa4840-7ccf-4589-9923-f633a98be8fe, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:22,624 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:22,625 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:22,626 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 00646f46-1f60-4b66-a304-ae3e0cf5c728, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:22,627 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 00646f46-1f60-4b66-a304-ae3e0cf5c728, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:22,628 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 00646f46-1f60-4b66-a304-ae3e0cf5c728, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:25,460 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:25,461 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318960, 'BlobCount': 128}}
2025-11-05 12:25:25,461 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:25,462 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a59170ef-9547-45cd-a6b8-c39f1945c059, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:25,463 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a59170ef-9547-45cd-a6b8-c39f1945c059, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:25,494 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a59170ef-9547-45cd-a6b8-c39f1945c059, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:27,915 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:27,916 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:27,917 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c51ee3b-397a-4c0b-9eea-ab04db901a80, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:27,918 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c51ee3b-397a-4c0b-9eea-ab04db901a80, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:27,923 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c51ee3b-397a-4c0b-9eea-ab04db901a80, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:28,484 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:28,485 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:28,486 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2536dbd3-ec8d-4146-b4db-784f1586b23a, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:28,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2536dbd3-ec8d-4146-b4db-784f1586b23a, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:28,494 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2536dbd3-ec8d-4146-b4db-784f1586b23a, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:29,088 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:29,089 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638256, 'BlobCount': 256}}
2025-11-05 12:25:29,089 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:29,090 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 91a1b937-7b49-4879-8556-22f4fa69f8df, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:29,091 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 91a1b937-7b49-4879-8556-22f4fa69f8df, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:29,096 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 91a1b937-7b49-4879-8556-22f4fa69f8df, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:31,003 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:31,004 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:31,005 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e4e218b6-c0f1-4868-b831-5ea49e3d635e, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:31,006 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e4e218b6-c0f1-4868-b831-5ea49e3d635e, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:31,009 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e4e218b6-c0f1-4868-b831-5ea49e3d635e, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:31,813 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:31,814 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:31,814 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a1b786e0-e82f-4c82-bd2d-294978a12f0f, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:31,816 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a1b786e0-e82f-4c82-bd2d-294978a12f0f, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:31,817 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a1b786e0-e82f-4c82-bd2d-294978a12f0f, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:32,623 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:32,624 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957296, 'BlobCount': 384}}
2025-11-05 12:25:32,624 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:32,625 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b3728a5-ea76-41fe-97da-4d373ced1fb1, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:32,626 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b3728a5-ea76-41fe-97da-4d373ced1fb1, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:32,628 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b3728a5-ea76-41fe-97da-4d373ced1fb1, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:34,453 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:34,454 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:34,454 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1341e63c-4d9f-4492-af80-e008af9f3807, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:34,455 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1341e63c-4d9f-4492-af80-e008af9f3807, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:34,457 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1341e63c-4d9f-4492-af80-e008af9f3807, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:34,801 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:34,801 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:34,802 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4cac413a-7647-47ac-8d3c-897fc8707373, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:34,803 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4cac413a-7647-47ac-8d3c-897fc8707373, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:34,806 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4cac413a-7647-47ac-8d3c-897fc8707373, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:35,658 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:35,659 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276656, 'BlobCount': 512}}
2025-11-05 12:25:35,660 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:35,660 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c681233e-f999-451f-9926-1e677e02cf7d, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:35,661 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c681233e-f999-451f-9926-1e677e02cf7d, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:35,663 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c681233e-f999-451f-9926-1e677e02cf7d, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:36,455 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 12:25:36,455 - 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:25:36,493 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-05 12:25:36,494 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 3eae97b4-492c-493e-9886-a5615d4bd811, localhost:17368): creating call state
2025-11-05 12:25:36,495 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 3eae97b4-492c-493e-9886-a5615d4bd811, localhost:17368): request = { database: "/Root" }
2025-11-05 12:25:36,503 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 3eae97b4-492c-493e-9886-a5615d4bd811, localhost:17368): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-anwhr6cmha.auto.internal" port: 17368 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:25:36,504 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-anwhr6cmha.auto.internal:17368, location 1, ssl: False>]>
2025-11-05 12:25:36,504 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17368
2025-11-05 12:25:37,226 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:37,227 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:37,228 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ad42645d-71ba-48a6-869e-5400d9772656, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:37,231 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ad42645d-71ba-48a6-869e-5400d9772656, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:37,232 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ad42645d-71ba-48a6-869e-5400d9772656, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:37,900 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:37,900 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:37,901 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49ebc829-c85b-4780-b778-55d78e8f9454, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:37,902 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49ebc829-c85b-4780-b778-55d78e8f9454, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:37,904 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49ebc829-c85b-4780-b778-55d78e8f9454, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:38,775 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:38,776 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595616, 'BlobCount': 640}}
2025-11-05 12:25:38,776 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:38,777 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09274048-bd9b-4905-8a5d-58d9ad6ce1f9, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:38,778 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09274048-bd9b-4905-8a5d-58d9ad6ce1f9, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:38,781 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09274048-bd9b-4905-8a5d-58d9ad6ce1f9, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:40,508 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:40,508 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:40,509 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4056b619-7bd6-4998-bbdc-f1ed797e29ab, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:40,510 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4056b619-7bd6-4998-bbdc-f1ed797e29ab, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:40,511 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4056b619-7bd6-4998-bbdc-f1ed797e29ab, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:41,387 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:41,388 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:41,388 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 78490a25-130d-40cf-85ff-9512261f5b91, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:41,389 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 78490a25-130d-40cf-85ff-9512261f5b91, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:41,391 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 78490a25-130d-40cf-85ff-9512261f5b91, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:42,416 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:42,416 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914800, 'BlobCount': 768}}
2025-11-05 12:25:42,417 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:42,418 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 834bf523-5230-43ff-af3b-05eac376fafd, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:42,422 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 834bf523-5230-43ff-af3b-05eac376fafd, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:42,423 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 834bf523-5230-43ff-af3b-05eac376fafd, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:44,078 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:44,079 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:44,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a54031b7-69b8-4332-a800-b8005889f45c, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:44,081 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a54031b7-69b8-4332-a800-b8005889f45c, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:44,083 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a54031b7-69b8-4332-a800-b8005889f45c, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:44,554 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:44,555 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:44,555 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec461a93-babe-4fd5-aa45-7562559257a7, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:44,557 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec461a93-babe-4fd5-aa45-7562559257a7, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:44,562 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec461a93-babe-4fd5-aa45-7562559257a7, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:45,696 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:45,697 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233936, 'BlobCount': 896}}
2025-11-05 12:25:45,698 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:45,698 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d24029be-8939-46b0-9e06-5414518612f6, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:45,699 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d24029be-8939-46b0-9e06-5414518612f6, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:45,701 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d24029be-8939-46b0-9e06-5414518612f6, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:47,548 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:47,549 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:47,550 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e18904ef-e25e-4ede-a435-81da5ec3b5a4, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:47,551 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e18904ef-e25e-4ede-a435-81da5ec3b5a4, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:47,554 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e18904ef-e25e-4ede-a435-81da5ec3b5a4, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:48,045 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:48,046 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:48,047 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a02202ff-224a-48e5-beb5-a2a1e0d0fea8, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:48,048 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a02202ff-224a-48e5-beb5-a2a1e0d0fea8, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:48,059 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a02202ff-224a-48e5-beb5-a2a1e0d0fea8, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:49,340 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:49,340 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553424, 'BlobCount': 1024}}
2025-11-05 12:25:49,341 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:49,341 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92b612b0-f4cf-4f88-913a-e8eb42e1427b, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:49,342 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92b612b0-f4cf-4f88-913a-e8eb42e1427b, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:49,346 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92b612b0-f4cf-4f88-913a-e8eb42e1427b, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:50,856 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:50,856 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:50,857 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0d705352-7c1a-46b3-ae5c-8b621b929f24, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:50,858 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0d705352-7c1a-46b3-ae5c-8b621b929f24, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:50,859 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0d705352-7c1a-46b3-ae5c-8b621b929f24, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:51,316 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:51,317 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:51,318 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ccee98aa-1cf7-432a-8ec5-c8da0e28d584, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:51,319 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ccee98aa-1cf7-432a-8ec5-c8da0e28d584, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:51,324 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ccee98aa-1cf7-432a-8ec5-c8da0e28d584, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:52,457 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:52,458 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872432, 'BlobCount': 1152}}
2025-11-05 12:25:52,459 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:52,459 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a5fc56a1-fadb-4fc9-90cf-2ec0128645c8, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:52,460 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a5fc56a1-fadb-4fc9-90cf-2ec0128645c8, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:52,470 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a5fc56a1-fadb-4fc9-90cf-2ec0128645c8, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:54,142 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:54,143 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:54,143 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 99162fe1-4e98-4226-8902-98106d4edb7a, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:54,144 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 99162fe1-4e98-4226-8902-98106d4edb7a, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:54,149 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 99162fe1-4e98-4226-8902-98106d4edb7a, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:54,697 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:54,698 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:54,699 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9763b98e-be98-4a2f-8f3e-ce03900f27a5, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:54,700 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9763b98e-be98-4a2f-8f3e-ce03900f27a5, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:54,706 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9763b98e-be98-4a2f-8f3e-ce03900f27a5, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:55,927 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:55,927 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191936, 'BlobCount': 1280}}
2025-11-05 12:25:55,928 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:55,928 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fb8f0835-7dcd-432b-af31-dc420d9b285e, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:55,929 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fb8f0835-7dcd-432b-af31-dc420d9b285e, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_3_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:55,948 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fb8f0835-7dcd-432b-af31-dc420d9b285e, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:57,855 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:57,856 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:57,856 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c44c0149-a0a2-4d95-b5bf-6696821d5d42, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:57,857 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c44c0149-a0a2-4d95-b5bf-6696821d5d42, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:57,860 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c44c0149-a0a2-4d95-b5bf-6696821d5d42, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:59,216 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:59,217 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:59,218 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 76990249-df8f-4363-a019-266d894d6d4a, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:59,219 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 76990249-df8f-4363-a019-266d894d6d4a, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:59,220 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 76990249-df8f-4363-a019-266d894d6d4a, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:59,685 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:59,686 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:25:59,687 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6a1c47ad-8bd3-4e7a-96ff-97ea7db1d640, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:25:59,688 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6a1c47ad-8bd3-4e7a-96ff-97ea7db1d640, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:59,690 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6a1c47ad-8bd3-4e7a-96ff-97ea7db1d640, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:04,685 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:14,767 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:14,768 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e3b2176f-89c6-4f62-9dc9-ef127e4984f3, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:26:14,769 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e3b2176f-89c6-4f62-9dc9-ef127e4984f3, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:14,780 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e3b2176f-89c6-4f62-9dc9-ef127e4984f3, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:21,444 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:31,491 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:31,492 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 88598b64-ad96-4938-a795-0d4f22138016, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:26:31,493 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 88598b64-ad96-4938-a795-0d4f22138016, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:31,498 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 88598b64-ad96-4938-a795-0d4f22138016, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:33,869 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:43,879 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:43,879 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61d5e0f1-a685-488d-b161-8bd9add37a9a, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:26:43,880 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61d5e0f1-a685-488d-b161-8bd9add37a9a, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:43,881 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61d5e0f1-a685-488d-b161-8bd9add37a9a, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:45,043 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:55,048 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:55,050 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 57619128-6d14-493d-a17b-295e5cc58fd6, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:26:55,050 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 57619128-6d14-493d-a17b-295e5cc58fd6, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:55,052 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 57619128-6d14-493d-a17b-295e5cc58fd6, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:56,772 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:56,777 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_3_compression`, volumes `value` (8000000, 1466080)
2025-11-05 12:26:56,785 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:56,786 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7ac2488f-da0f-4f90-ac9e-d10474cb406d, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:26:56,787 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7ac2488f-da0f-4f90-ac9e-d10474cb406d, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:56,789 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7ac2488f-da0f-4f90-ac9e-d10474cb406d, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:57,217 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:57,218 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:26:57,218 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 571a2913-85b4-44a8-90e9-20fa10890fe4, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:26:57,219 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 571a2913-85b4-44a8-90e9-20fa10890fe4, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:26:57,221 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 571a2913-85b4-44a8-90e9-20fa10890fe4, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:26:58,165 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:27:08,172 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:27:08,173 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7d40fe1-c0e5-475a-a034-9b3fac0b49bc, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:27:08,174 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7d40fe1-c0e5-475a-a034-9b3fac0b49bc, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:27:08,175 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7d40fe1-c0e5-475a-a034-9b3fac0b49bc, ghrun-anwhr6cmha.auto.internal:17368): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:27:09,281 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=
2025-11-05 12:27:09,290 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_3_compression`, volumes `value` (8000000, 1466080)
2025-11-05 12:27:09,291 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_3_compression` 8092160 / 1466080: 5.519589654043435
2025-11-05 12:27:09,293 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-05 12:27:09,294 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 34c9db7f-8466-4d34-b490-0b851de0dcf4, ghrun-anwhr6cmha.auto.internal:17368): creating call state
2025-11-05 12:27:09,295 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 34c9db7f-8466-4d34-b490-0b851de0dcf4, ghrun-anwhr6cmha.auto.internal:17368): request = { session_id: "ydb://session/3?node_id=1&id=NDg3NzI3YzgtYjliMTQ1ZTUtNGM0NGMxZGEtNzNiYzMxYzE=" }
2025-11-05 12:27:09,299 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 34c9db7f-8466-4d34-b490-0b851de0dcf4, ghrun-anwhr6cmha.auto.internal:17368): response = { status: SUCCESS }
2025-11-05 12:27:09,309 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-05 12:27:09,309 - DEBUG - ydb.topic - close: Close topic client
2025-11-05 12:27:09,309 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-05 12:27:09,310 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17368
2025-11-05 12:27:09,314 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-anwhr6cmha.auto.internal:17368
2025-11-05 12:27:09,316 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-05 12:27:26,335 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:17368/1
2025-11-05 12:27:26,336 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25173
2025-11-05 12:27:26,345 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10177
2025-11-05 12:27:26,346 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16160
2025-11-05 12:27:26,346 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17368
2025-11-05 12:27:26,346 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6505
