2025-11-01 16:23:37,677 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:23:37,677 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_17_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 17]
2025-11-01 16:23:37,677 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:23:37,678 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:23:37,679 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_17_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 17])
2025-11-01 16:23:37,680 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:37,681 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ee3465b0-cf0e-4bff-a503-0ba822871074, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:37,681 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ee3465b0-cf0e-4bff-a503-0ba822871074, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_17_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 17)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:37,683 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ee3465b0-cf0e-4bff-a503-0ba822871074, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:40,329 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:40,330 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_17_compression created
2025-11-01 16:23:40,330 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:40,331 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5e2805f8-b619-4f0a-ba03-9ac1b31530dd, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:40,332 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5e2805f8-b619-4f0a-ba03-9ac1b31530dd, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_17_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:40,337 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5e2805f8-b619-4f0a-ba03-9ac1b31530dd, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:41,330 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:41,330 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:41,331 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 725fc109-6930-4787-825b-ddb341dfc8e7, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:41,332 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 725fc109-6930-4787-825b-ddb341dfc8e7, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:41,334 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 725fc109-6930-4787-825b-ddb341dfc8e7, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:46,036 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:46,037 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:46,037 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 672ea0e8-cfb2-4fa9-916c-b6c55d9e754b, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:46,038 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 672ea0e8-cfb2-4fa9-916c-b6c55d9e754b, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:46,039 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 672ea0e8-cfb2-4fa9-916c-b6c55d9e754b, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:46,819 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:46,819 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:46,820 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 29dfaa68-8c07-4b27-8d2d-78dc3582a574, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:46,821 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 29dfaa68-8c07-4b27-8d2d-78dc3582a574, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:46,822 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 29dfaa68-8c07-4b27-8d2d-78dc3582a574, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:48,513 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:48,514 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-01 16:23:48,514 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:48,515 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca62cffa-983f-471d-907a-414592948276, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:48,519 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca62cffa-983f-471d-907a-414592948276, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:48,521 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca62cffa-983f-471d-907a-414592948276, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:52,298 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:52,299 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:52,299 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 30d81c43-26a5-4a48-85c6-50e8f20510c4, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:52,300 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 30d81c43-26a5-4a48-85c6-50e8f20510c4, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:52,302 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 30d81c43-26a5-4a48-85c6-50e8f20510c4, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:52,559 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:52,560 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:52,561 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4c5bebc2-76a2-4d12-951f-89ddd337af86, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:52,562 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4c5bebc2-76a2-4d12-951f-89ddd337af86, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:52,564 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4c5bebc2-76a2-4d12-951f-89ddd337af86, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:52,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:52,965 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-01 16:23:52,967 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:52,967 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 55f10260-034a-42f1-9500-62c759033463, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:52,968 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 55f10260-034a-42f1-9500-62c759033463, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:52,971 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 55f10260-034a-42f1-9500-62c759033463, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:56,685 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:56,685 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:56,686 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 29e3c5b4-cd86-4638-8989-7ac6142de29d, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:56,688 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 29e3c5b4-cd86-4638-8989-7ac6142de29d, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:56,690 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 29e3c5b4-cd86-4638-8989-7ac6142de29d, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:57,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:57,138 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:57,139 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed1491ab-68d9-4b7f-b168-f2fe63e4ddd9, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:57,139 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed1491ab-68d9-4b7f-b168-f2fe63e4ddd9, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:57,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed1491ab-68d9-4b7f-b168-f2fe63e4ddd9, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:23:57,595 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:57,596 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-01 16:23:57,597 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:23:57,597 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c3cce174-a82a-4efb-99d1-9a5ecc6286f6, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:23:57,598 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c3cce174-a82a-4efb-99d1-9a5ecc6286f6, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:23:57,605 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c3cce174-a82a-4efb-99d1-9a5ecc6286f6, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:01,836 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:01,837 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:01,838 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1cfcc607-8b93-4152-b199-650898acdd8c, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:01,839 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1cfcc607-8b93-4152-b199-650898acdd8c, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:01,840 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1cfcc607-8b93-4152-b199-650898acdd8c, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:02,184 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:02,185 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:02,186 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 144e2340-997d-4c77-bdb3-ae9d89b807d5, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:02,190 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 144e2340-997d-4c77-bdb3-ae9d89b807d5, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:02,191 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 144e2340-997d-4c77-bdb3-ae9d89b807d5, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:02,650 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:02,651 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-01 16:24:02,651 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:02,652 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3bfe6169-6596-4a15-9071-6c793902502f, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:02,653 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3bfe6169-6596-4a15-9071-6c793902502f, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:02,655 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3bfe6169-6596-4a15-9071-6c793902502f, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:06,781 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:06,782 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:06,782 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5ded5914-d6d9-4e1a-9991-4a9c0a2cf003, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:06,783 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5ded5914-d6d9-4e1a-9991-4a9c0a2cf003, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:06,785 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5ded5914-d6d9-4e1a-9991-4a9c0a2cf003, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:07,087 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:07,088 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:07,089 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef28a794-a5cf-4833-8753-a3bdca18ca62, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:07,089 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef28a794-a5cf-4833-8753-a3bdca18ca62, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:07,091 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef28a794-a5cf-4833-8753-a3bdca18ca62, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:07,529 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:07,529 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-01 16:24:07,530 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:07,531 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d5738fd7-7bb4-4c4e-9ddc-adbf9cfa36de, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:07,531 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d5738fd7-7bb4-4c4e-9ddc-adbf9cfa36de, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:07,533 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d5738fd7-7bb4-4c4e-9ddc-adbf9cfa36de, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:07,665 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:24:07,666 - 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-01 16:24:07,693 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:24:07,693 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, e839f063-b062-4525-8e27-7348bce9930d, localhost:29100): creating call state
2025-11-01 16:24:07,694 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, e839f063-b062-4525-8e27-7348bce9930d, localhost:29100): request = { database: "/Root" }
2025-11-01 16:24:07,702 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, e839f063-b062-4525-8e27-7348bce9930d, localhost:29100): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-cjocww3of4.auto.internal" port: 29100 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-01 16:24:07,703 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-cjocww3of4.auto.internal:29100, location 1, ssl: False>]>
2025-11-01 16:24:07,704 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:29100
2025-11-01 16:24:11,353 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:11,354 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:11,355 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2fb07ab1-c368-4733-a5e6-18caad978781, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:11,355 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2fb07ab1-c368-4733-a5e6-18caad978781, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:11,361 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2fb07ab1-c368-4733-a5e6-18caad978781, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:11,726 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:11,727 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:11,727 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb89e328-8b11-4f76-a2c3-c02c81bbbe2a, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:11,728 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb89e328-8b11-4f76-a2c3-c02c81bbbe2a, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:11,730 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb89e328-8b11-4f76-a2c3-c02c81bbbe2a, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:12,188 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:12,189 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-01 16:24:12,189 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:12,197 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d86409d9-37e5-41e0-8fec-40bdd1025cbb, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:12,199 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d86409d9-37e5-41e0-8fec-40bdd1025cbb, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:12,200 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d86409d9-37e5-41e0-8fec-40bdd1025cbb, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:16,181 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:16,182 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:16,183 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8f327abd-bc12-4077-aefa-5d3d16356d32, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:16,184 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8f327abd-bc12-4077-aefa-5d3d16356d32, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:16,186 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8f327abd-bc12-4077-aefa-5d3d16356d32, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:16,541 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:16,542 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:16,543 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ff36ee3-d627-42a9-8751-38b7bd7b2310, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:16,544 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ff36ee3-d627-42a9-8751-38b7bd7b2310, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:16,545 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ff36ee3-d627-42a9-8751-38b7bd7b2310, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:17,078 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:17,079 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-01 16:24:17,080 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:17,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b219e32-2492-4b14-9150-de734d6e19ce, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:17,081 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b219e32-2492-4b14-9150-de734d6e19ce, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:17,084 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b219e32-2492-4b14-9150-de734d6e19ce, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:20,893 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:20,894 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:20,894 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48aae447-2d01-402c-89ab-e74500dd83ba, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:20,895 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48aae447-2d01-402c-89ab-e74500dd83ba, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:20,896 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48aae447-2d01-402c-89ab-e74500dd83ba, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:21,168 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:21,169 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:21,170 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2e56160d-eddb-4d8e-aa0b-8c42ce936b57, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:21,171 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2e56160d-eddb-4d8e-aa0b-8c42ce936b57, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:21,173 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2e56160d-eddb-4d8e-aa0b-8c42ce936b57, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:21,706 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:21,707 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-01 16:24:21,707 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:21,708 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db397a15-2ecb-4427-9f8d-c209eb5d29e6, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:21,710 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db397a15-2ecb-4427-9f8d-c209eb5d29e6, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:21,711 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db397a15-2ecb-4427-9f8d-c209eb5d29e6, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:25,321 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:25,322 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:25,322 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e344628-3536-48fb-bc88-ba6a77327cf5, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:25,323 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e344628-3536-48fb-bc88-ba6a77327cf5, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:25,325 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e344628-3536-48fb-bc88-ba6a77327cf5, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:25,645 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:25,646 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:25,647 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d53ca15e-73e1-429c-8fcf-ddf502730165, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:25,648 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d53ca15e-73e1-429c-8fcf-ddf502730165, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:25,650 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d53ca15e-73e1-429c-8fcf-ddf502730165, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:26,242 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:26,243 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-01 16:24:26,243 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:26,244 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 18d888b4-06b3-4330-9cac-a1f280b4bbfc, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:26,246 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 18d888b4-06b3-4330-9cac-a1f280b4bbfc, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_17_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:26,248 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 18d888b4-06b3-4330-9cac-a1f280b4bbfc, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:29,921 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:29,922 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:29,923 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e958c242-4f23-4d4c-81fa-000716dd85d2, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:29,923 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e958c242-4f23-4d4c-81fa-000716dd85d2, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:29,925 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e958c242-4f23-4d4c-81fa-000716dd85d2, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:30,252 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:30,253 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:30,254 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 05553a30-760f-4a69-a002-79de4d97226f, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:30,255 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 05553a30-760f-4a69-a002-79de4d97226f, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:30,256 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 05553a30-760f-4a69-a002-79de4d97226f, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:30,849 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:30,850 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-01 16:24:30,850 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:30,851 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5a7fab93-ce5c-4b0d-8116-07fa36e7d385, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:30,852 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5a7fab93-ce5c-4b0d-8116-07fa36e7d385, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_17_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:30,853 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5a7fab93-ce5c-4b0d-8116-07fa36e7d385, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:31,824 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:31,825 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:31,825 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 877cc8d6-3dce-4c19-b124-fd46e40ad19a, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:31,826 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 877cc8d6-3dce-4c19-b124-fd46e40ad19a, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:31,827 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 877cc8d6-3dce-4c19-b124-fd46e40ad19a, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:32,154 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:32,155 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:32,156 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f30b16a8-0e4c-4c3b-8e39-7dc7c1bf564c, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:32,156 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f30b16a8-0e4c-4c3b-8e39-7dc7c1bf564c, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:32,158 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f30b16a8-0e4c-4c3b-8e39-7dc7c1bf564c, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:32,512 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:32,513 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:32,514 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd3798a8-b013-40f2-9ac6-6be28a538a7a, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:32,514 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd3798a8-b013-40f2-9ac6-6be28a538a7a, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:32,516 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd3798a8-b013-40f2-9ac6-6be28a538a7a, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:36,263 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:46,301 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:46,301 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 307f144a-01d0-4f3e-922a-576d8aaca3fe, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:46,302 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 307f144a-01d0-4f3e-922a-576d8aaca3fe, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:46,304 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 307f144a-01d0-4f3e-922a-576d8aaca3fe, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:48,492 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:58,509 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:24:58,510 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be339e96-a683-4d6d-86d6-b479f39e8519, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:24:58,511 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be339e96-a683-4d6d-86d6-b479f39e8519, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:24:58,512 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be339e96-a683-4d6d-86d6-b479f39e8519, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:24:59,056 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:25:09,061 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:25:09,062 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 63201fdf-3500-4d34-9860-5eefd06c31de, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:25:09,063 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 63201fdf-3500-4d34-9860-5eefd06c31de, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:25:09,064 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 63201fdf-3500-4d34-9860-5eefd06c31de, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:25:09,569 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:25:09,573 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_17_compression`, volumes `value` (8000000, 1497984)
2025-11-01 16:25:09,574 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:25:09,574 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e78b52c9-0a7a-4f1f-bc10-84bcfdd7b287, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:25:09,575 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e78b52c9-0a7a-4f1f-bc10-84bcfdd7b287, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:25:09,576 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e78b52c9-0a7a-4f1f-bc10-84bcfdd7b287, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:25:09,765 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:25:09,766 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:25:09,766 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a115a4cc-cd97-43ef-a28c-6085879fefcd, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:25:09,767 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a115a4cc-cd97-43ef-a28c-6085879fefcd, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:25:09,768 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a115a4cc-cd97-43ef-a28c-6085879fefcd, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:25:10,274 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:25:20,279 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:25:20,280 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f437b7dc-ee68-4e62-89aa-17a9fb94678a, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:25:20,281 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f437b7dc-ee68-4e62-89aa-17a9fb94678a, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_17_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:25:20,282 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f437b7dc-ee68-4e62-89aa-17a9fb94678a, ghrun-cjocww3of4.auto.internal:29100): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:25:20,817 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=
2025-11-01 16:25:20,821 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_17_compression`, volumes `value` (8000000, 1497984)
2025-11-01 16:25:20,822 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_17_compression` 8092160 / 1497984: 5.402033666581218
2025-11-01 16:25:20,824 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 16:25:20,825 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, a118f5c1-adb9-4a3e-923a-8a9393fbcbce, ghrun-cjocww3of4.auto.internal:29100): creating call state
2025-11-01 16:25:20,826 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, a118f5c1-adb9-4a3e-923a-8a9393fbcbce, ghrun-cjocww3of4.auto.internal:29100): request = { session_id: "ydb://session/3?node_id=1&id=MjNlZTE3YjAtZTBmODYxOWMtZDQwNGZkMWYtNTUxY2Y4MGU=" }
2025-11-01 16:25:20,830 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, a118f5c1-adb9-4a3e-923a-8a9393fbcbce, ghrun-cjocww3of4.auto.internal:29100): response = { status: SUCCESS }
2025-11-01 16:25:20,832 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-01 16:25:20,833 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 16:25:20,834 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 16:25:20,834 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:29100
2025-11-01 16:25:20,836 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-cjocww3of4.auto.internal:29100
2025-11-01 16:25:20,841 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 16:25:32,844 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:29100/1
2025-11-01 16:25:32,846 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20157
2025-11-01 16:25:32,846 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11268
2025-11-01 16:25:32,846 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2631
2025-11-01 16:25:32,847 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29100
2025-11-01 16:25:32,847 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31970
