2025-11-21 20:48:39,940 - INFO - ya.test.671285  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:48:39,941 - INFO - ya.test.671285  - pytest_runtest_setup: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14]
2025-11-21 20:48:39,941 - INFO - ya.test.671285  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:48:39,941 - INFO - ya.test.671285  - pytest_runtest_setup: Test setup
2025-11-21 20:48:39,943 - INFO - ya.test.671285  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14])
2025-11-21 20:48:39,944 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:39,945 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, d3b4beca-d6c5-4606-93bb-5d92fc278466, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:48:39,946 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, d3b4beca-d6c5-4606-93bb-5d92fc278466, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_14_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 14)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:39,947 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, d3b4beca-d6c5-4606-93bb-5d92fc278466, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:45,480 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:45,480 - INFO - __tests__.alter_compression.671285  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_14_compression created
2025-11-21 20:48:45,481 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:45,481 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, aa4c4098-0fcc-4c9e-aec2-2f76804ad2ff, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:48:45,482 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, aa4c4098-0fcc-4c9e-aec2-2f76804ad2ff, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:45,489 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, aa4c4098-0fcc-4c9e-aec2-2f76804ad2ff, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:46,547 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:46,548 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:46,549 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 0e3738b6-7765-4c77-9b37-cc0490957d14, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:48:46,550 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 0e3738b6-7765-4c77-9b37-cc0490957d14, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:46,551 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 0e3738b6-7765-4c77-9b37-cc0490957d14, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:51,276 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:51,277 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:51,278 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, f2a9569f-9e69-44df-a812-9ab92e412a86, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:48:51,279 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, f2a9569f-9e69-44df-a812-9ab92e412a86, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:51,280 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, f2a9569f-9e69-44df-a812-9ab92e412a86, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:52,488 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:52,489 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:52,489 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 74f726c6-fa76-446f-97ff-0f34b8599b53, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:48:52,490 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 74f726c6-fa76-446f-97ff-0f34b8599b53, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:52,492 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 74f726c6-fa76-446f-97ff-0f34b8599b53, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:55,243 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:55,244 - INFO - __tests__.alter_compression.671285  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-11-21 20:48:55,245 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:55,245 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 0ac1344c-b6bf-40ee-8623-ee463a0db9ca, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:48:55,246 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 0ac1344c-b6bf-40ee-8623-ee463a0db9ca, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:55,258 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 0ac1344c-b6bf-40ee-8623-ee463a0db9ca, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:59,995 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:59,996 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:48:59,996 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 6fa08993-af9f-49e3-8830-f9b43dd83916, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:48:59,997 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 6fa08993-af9f-49e3-8830-f9b43dd83916, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:59,999 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 6fa08993-af9f-49e3-8830-f9b43dd83916, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:00,352 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:00,352 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:00,353 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 43cc0de0-98b1-4766-8737-d6c21df9d3ac, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:00,354 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 43cc0de0-98b1-4766-8737-d6c21df9d3ac, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:00,356 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 43cc0de0-98b1-4766-8737-d6c21df9d3ac, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:01,080 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:01,081 - INFO - __tests__.alter_compression.671285  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-11-21 20:49:01,081 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:01,082 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, e73dffc3-d4f2-4820-9d00-54c67109bc5d, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:01,083 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, e73dffc3-d4f2-4820-9d00-54c67109bc5d, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:01,085 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, e73dffc3-d4f2-4820-9d00-54c67109bc5d, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:05,420 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:05,421 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:05,422 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, c93fbc2e-e3a6-4e8d-bd37-1bc926d26967, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:05,423 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, c93fbc2e-e3a6-4e8d-bd37-1bc926d26967, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:05,424 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, c93fbc2e-e3a6-4e8d-bd37-1bc926d26967, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:05,888 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:05,889 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:05,889 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 09086ad0-5b25-4949-8b84-af8ee71dbcd6, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:05,890 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 09086ad0-5b25-4949-8b84-af8ee71dbcd6, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:05,899 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 09086ad0-5b25-4949-8b84-af8ee71dbcd6, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:06,803 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:06,804 - INFO - __tests__.alter_compression.671285  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-11-21 20:49:06,804 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:06,805 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, e88aae51-ca09-4d38-a080-1afb32b96e8a, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:06,806 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, e88aae51-ca09-4d38-a080-1afb32b96e8a, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:06,816 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, e88aae51-ca09-4d38-a080-1afb32b96e8a, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:10,727 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:10,727 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:10,728 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 12c1de51-2155-49fc-ba57-51a1d169b9ba, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:10,729 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 12c1de51-2155-49fc-ba57-51a1d169b9ba, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:10,730 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 12c1de51-2155-49fc-ba57-51a1d169b9ba, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:11,129 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:11,130 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:11,131 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 0623afe5-c24d-4255-9686-61c55328d587, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:11,131 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 0623afe5-c24d-4255-9686-61c55328d587, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:11,133 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 0623afe5-c24d-4255-9686-61c55328d587, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:11,734 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:11,735 - INFO - __tests__.alter_compression.671285  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-11-21 20:49:11,735 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:11,736 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 19911acd-3b3e-4fe3-a2fc-b452cb8ea9be, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:11,736 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 19911acd-3b3e-4fe3-a2fc-b452cb8ea9be, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:11,737 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 19911acd-3b3e-4fe3-a2fc-b452cb8ea9be, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:15,255 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:15,256 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:15,256 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 801e59e5-944c-4d9b-bbb9-81f4155839bb, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:15,257 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 801e59e5-944c-4d9b-bbb9-81f4155839bb, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:15,258 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 801e59e5-944c-4d9b-bbb9-81f4155839bb, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:15,696 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:15,697 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:15,698 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 844e96d5-de44-49a5-b9aa-1e471b62c400, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:15,698 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 844e96d5-de44-49a5-b9aa-1e471b62c400, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:15,700 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 844e96d5-de44-49a5-b9aa-1e471b62c400, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:16,350 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:16,351 - INFO - __tests__.alter_compression.671285  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-11-21 20:49:16,351 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:16,352 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, eb8be1f8-1619-440a-8234-ce868d744d20, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:16,353 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, eb8be1f8-1619-440a-8234-ce868d744d20, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:16,355 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, eb8be1f8-1619-440a-8234-ce868d744d20, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:19,944 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:19,945 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:19,945 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 8735e94f-c0e2-4e6f-9c4e-74f664d47b4c, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:19,946 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 8735e94f-c0e2-4e6f-9c4e-74f664d47b4c, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:19,947 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 8735e94f-c0e2-4e6f-9c4e-74f664d47b4c, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:20,383 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:20,384 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:20,385 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, b4d415fc-e0bc-409c-b61e-c278879ba634, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:20,385 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, b4d415fc-e0bc-409c-b61e-c278879ba634, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:20,390 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, b4d415fc-e0bc-409c-b61e-c278879ba634, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:21,064 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:21,065 - INFO - __tests__.alter_compression.671285  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-11-21 20:49:21,066 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:21,066 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 9b808e06-5874-444e-9f44-d5121af742a8, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:21,067 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 9b808e06-5874-444e-9f44-d5121af742a8, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:21,070 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 9b808e06-5874-444e-9f44-d5121af742a8, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:24,711 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:24,712 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:24,712 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, c7ad8b9c-97b0-48b8-a3c3-8536e818e005, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:24,713 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, c7ad8b9c-97b0-48b8-a3c3-8536e818e005, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:24,714 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, c7ad8b9c-97b0-48b8-a3c3-8536e818e005, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:25,425 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:25,425 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:25,427 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, dd03d377-5209-42f1-acf7-c201cd854d98, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:25,428 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, dd03d377-5209-42f1-acf7-c201cd854d98, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:25,437 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, dd03d377-5209-42f1-acf7-c201cd854d98, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:26,408 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:26,409 - INFO - __tests__.alter_compression.671285  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-11-21 20:49:26,409 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:26,410 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 575781f2-7ea8-4438-9243-6b040b1f5289, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:26,411 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 575781f2-7ea8-4438-9243-6b040b1f5289, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:26,413 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 575781f2-7ea8-4438-9243-6b040b1f5289, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:30,446 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:30,447 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:30,448 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 574ea85a-c897-4986-856e-9d2743233ab2, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:30,448 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 574ea85a-c897-4986-856e-9d2743233ab2, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:30,450 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 574ea85a-c897-4986-856e-9d2743233ab2, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:30,931 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:30,932 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:30,932 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 0c445e82-0ecf-4b16-84d7-6b7a28449c72, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:30,933 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 0c445e82-0ecf-4b16-84d7-6b7a28449c72, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:30,934 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 0c445e82-0ecf-4b16-84d7-6b7a28449c72, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:31,831 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:31,832 - INFO - __tests__.alter_compression.671285  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-11-21 20:49:31,832 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:31,833 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, b58497f8-b086-452d-be21-8020b82318db, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:31,834 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, b58497f8-b086-452d-be21-8020b82318db, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:31,835 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, b58497f8-b086-452d-be21-8020b82318db, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:35,647 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:35,648 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:35,649 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 89371807-0f29-4289-af2d-858be12118e9, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:35,650 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 89371807-0f29-4289-af2d-858be12118e9, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:35,652 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 89371807-0f29-4289-af2d-858be12118e9, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:36,043 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:36,044 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:36,044 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 7ad1e4b0-04ab-49d1-978c-cc81ea474f67, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:36,045 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 7ad1e4b0-04ab-49d1-978c-cc81ea474f67, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:36,056 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 7ad1e4b0-04ab-49d1-978c-cc81ea474f67, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:37,319 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:37,320 - INFO - __tests__.alter_compression.671285  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-11-21 20:49:37,320 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:37,321 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, e8f40764-a92b-484c-9213-198d48fcd216, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:37,322 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, e8f40764-a92b-484c-9213-198d48fcd216, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:37,336 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, e8f40764-a92b-484c-9213-198d48fcd216, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:41,336 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:41,337 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:41,338 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 84c21974-d9d9-469b-8092-dae65d161e6e, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:41,338 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 84c21974-d9d9-469b-8092-dae65d161e6e, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:41,340 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 84c21974-d9d9-469b-8092-dae65d161e6e, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:41,863 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:41,864 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:41,864 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, f6ec5692-2858-45bf-89d7-14861d826aab, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:41,865 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, f6ec5692-2858-45bf-89d7-14861d826aab, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:41,871 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, f6ec5692-2858-45bf-89d7-14861d826aab, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:43,089 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:43,090 - INFO - __tests__.alter_compression.671285  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-11-21 20:49:43,090 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:43,091 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 24bab9f2-8ed2-4fc6-b501-36316c216043, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:43,091 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 24bab9f2-8ed2-4fc6-b501-36316c216043, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:43,093 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 24bab9f2-8ed2-4fc6-b501-36316c216043, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:46,157 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:46,158 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:46,158 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 34472085-5a85-4659-a7a1-a77864213b73, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:46,159 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 34472085-5a85-4659-a7a1-a77864213b73, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:46,160 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 34472085-5a85-4659-a7a1-a77864213b73, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:46,680 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:46,681 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:46,681 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, bdc8fbc8-8eb7-410b-ab60-308233fa4b6b, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:46,682 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, bdc8fbc8-8eb7-410b-ab60-308233fa4b6b, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:46,683 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, bdc8fbc8-8eb7-410b-ab60-308233fa4b6b, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:47,214 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:47,215 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:49:47,216 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, b7b367b5-eb52-4b7b-b155-e5075a02554b, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:49:47,216 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, b7b367b5-eb52-4b7b-b155-e5075a02554b, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:49:47,217 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, b7b367b5-eb52-4b7b-b155-e5075a02554b, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:49:52,026 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:02,102 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:02,103 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 79addb45-e37b-443c-8b12-07ceeb41a4c5, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:50:02,104 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 79addb45-e37b-443c-8b12-07ceeb41a4c5, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:50:02,105 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 79addb45-e37b-443c-8b12-07ceeb41a4c5, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:50:03,747 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:05,987 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671285  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 20:50:05,988 - DEBUG - ydb.connection.671285  - 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-21 20:50:06,035 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671285  - context_resolve: Resolving endpoints for database /Root
2025-11-21 20:50:06,036 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ListEndpoints, 65d3e390-5988-408b-b608-aafc8d7909ae, localhost:4219): creating call state
2025-11-21 20:50:06,036 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ListEndpoints, 65d3e390-5988-408b-b608-aafc8d7909ae, localhost:4219): request = { database: "/Root" }
2025-11-21 20:50:06,042 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ListEndpoints, 65d3e390-5988-408b-b608-aafc8d7909ae, localhost:4219): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-frzglb4yoy.auto.internal" port: 4219 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-21 20:50:06,043 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671285  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-frzglb4yoy.auto.internal:4219, location 1, ssl: False>]>
2025-11-21 20:50:06,044 - DEBUG - ydb.connection.671285  - close: Closing channel for endpoint localhost:4219
2025-11-21 20:50:13,758 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:13,759 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 677118f2-e317-4e50-8bec-dd55ec02892a, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:50:13,760 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 677118f2-e317-4e50-8bec-dd55ec02892a, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:50:13,761 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 677118f2-e317-4e50-8bec-dd55ec02892a, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:50:14,436 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:24,441 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:24,442 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, c2309c96-c3c1-446d-8a4e-7d51296e81de, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:50:24,443 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, c2309c96-c3c1-446d-8a4e-7d51296e81de, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:50:24,444 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, c2309c96-c3c1-446d-8a4e-7d51296e81de, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:50:25,160 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:25,164 - INFO - root.671285  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1870856)
2025-11-21 20:50:25,165 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:25,165 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 7e4f1648-4777-4dc2-91de-e63475e616c7, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:50:25,166 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 7e4f1648-4777-4dc2-91de-e63475e616c7, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:50:25,168 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 7e4f1648-4777-4dc2-91de-e63475e616c7, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:50:25,435 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:25,436 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:25,436 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, f445b283-f318-4311-966f-041ed1de1fd0, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:50:25,437 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, f445b283-f318-4311-966f-041ed1de1fd0, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:50:25,438 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, f445b283-f318-4311-966f-041ed1de1fd0, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:50:26,246 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:36,255 - DEBUG - ydb.query.pool.671285  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:36,256 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(ExecuteQuery, 13120dc3-ac40-49bf-950d-260a0a5bd6f9, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:50:36,257 - DEBUG - ydb.connection.671285  - _log_request: RpcState(ExecuteQuery, 13120dc3-ac40-49bf-950d-260a0a5bd6f9, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:50:36,270 - DEBUG - ydb.connection.671285  - _log_response: RpcState(ExecuteQuery, 13120dc3-ac40-49bf-950d-260a0a5bd6f9, ghrun-frzglb4yoy.auto.internal:4219): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:50:37,200 - DEBUG - ydb.query.pool.671285  - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=
2025-11-21 20:50:37,205 - INFO - root.671285  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1870856)
2025-11-21 20:50:37,206 - INFO - root.671285  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_14_compression` 8092160 / 1870856: 4.325378329491954
2025-11-21 20:50:37,208 - INFO - ya.test.671285  - pytest_runtest_teardown: Test teardown
2025-11-21 20:50:37,209 - DEBUG - ydb.connection.671285  - _prepare_call: RpcState(DeleteSession, 6b0e78ac-396c-4747-8c8d-27833c7e76cf, ghrun-frzglb4yoy.auto.internal:4219): creating call state
2025-11-21 20:50:37,209 - DEBUG - ydb.connection.671285  - _log_request: RpcState(DeleteSession, 6b0e78ac-396c-4747-8c8d-27833c7e76cf, ghrun-frzglb4yoy.auto.internal:4219): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1MGU5OWUtOTEzNWM1MGEtOGQ5MTM5ZTMtMTcxZDRhZTE=" }
2025-11-21 20:50:37,214 - DEBUG - ydb.connection.671285  - _log_response: RpcState(DeleteSession, 6b0e78ac-396c-4747-8c8d-27833c7e76cf, ghrun-frzglb4yoy.auto.internal:4219): response = { status: SUCCESS }
2025-11-21 20:50:37,215 - DEBUG - ydb.query.pool.671285  - stop: All session were deleted.
2025-11-21 20:50:37,215 - DEBUG - ydb.topic.671285  - close: Close topic client
2025-11-21 20:50:37,216 - DEBUG - ydb.topic.671285  - close: Topic client was closed
2025-11-21 20:50:37,216 - DEBUG - ydb.connection.671285  - close: Closing channel for endpoint localhost:4219
2025-11-21 20:50:37,217 - DEBUG - ydb.connection.671285  - close: Closing channel for endpoint ghrun-frzglb4yoy.auto.internal:4219
2025-11-21 20:50:37,219 - DEBUG - ydb.connection.671285  - _rpc_error_handler: RpcState(AttachSession, a2e5af93-5bc7-4294-a614-5a73060e921d, ghrun-frzglb4yoy.auto.internal:4219): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-21 20:50:37,221 - INFO - ydb.pool.Discovery.671285  - run: Successfully terminated discovery process
2025-11-21 20:50:49,231 - INFO - ydb.tests.library.harness.kikimr_runner.671285  - stop: Stopped node localhost:4219/1
2025-11-21 20:50:49,235 - DEBUG - library.python.filelock.671285  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11943
2025-11-21 20:50:49,235 - DEBUG - library.python.filelock.671285  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8145
2025-11-21 20:50:49,236 - DEBUG - library.python.filelock.671285  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23407
2025-11-21 20:50:49,236 - DEBUG - library.python.filelock.671285  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4219
2025-11-21 20:50:49,236 - DEBUG - library.python.filelock.671285  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13835
