2025-11-21 17:41:31,576 - INFO - ya.test.671694  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:41:31,577 - INFO - ya.test.671694  - pytest_runtest_setup: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11]
2025-11-21 17:41:31,577 - INFO - ya.test.671694  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:41:31,577 - INFO - ya.test.671694  - pytest_runtest_setup: Test setup
2025-11-21 17:41:31,580 - INFO - ya.test.671694  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11])
2025-11-21 17:41:31,580 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:31,581 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 0edcb627-e04b-453a-b984-fa502ccd8621, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:31,582 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 0edcb627-e04b-453a-b984-fa502ccd8621, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_11_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 11)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:31,584 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 0edcb627-e04b-453a-b984-fa502ccd8621, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:35,195 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:35,196 - INFO - __tests__.alter_compression.671694  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_11_compression created
2025-11-21 17:41:35,196 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:35,197 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 459820d8-edc7-4a5e-99b5-09642dd422e4, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:35,198 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 459820d8-edc7-4a5e-99b5-09642dd422e4, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_11_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:35,203 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 459820d8-edc7-4a5e-99b5-09642dd422e4, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:36,237 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:36,238 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:36,239 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 1f83355e-9df5-4c9e-ae15-123b0f42b109, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:36,240 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 1f83355e-9df5-4c9e-ae15-123b0f42b109, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:36,241 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 1f83355e-9df5-4c9e-ae15-123b0f42b109, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:39,494 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:39,495 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:39,496 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, a1e99404-665e-42f2-be42-fceccec32c8b, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:39,497 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, a1e99404-665e-42f2-be42-fceccec32c8b, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:39,499 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, a1e99404-665e-42f2-be42-fceccec32c8b, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:40,655 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:40,656 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:40,657 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 7e5628b3-178e-493e-850c-fff0ba065027, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:40,657 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 7e5628b3-178e-493e-850c-fff0ba065027, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:40,659 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 7e5628b3-178e-493e-850c-fff0ba065027, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:42,646 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:42,647 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 375776, 'BlobCount': 128}}
2025-11-21 17:41:42,647 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:42,648 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 81ca476e-3ccb-4be8-b7fa-f1a4755b8867, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:42,649 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 81ca476e-3ccb-4be8-b7fa-f1a4755b8867, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:42,650 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 81ca476e-3ccb-4be8-b7fa-f1a4755b8867, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:45,767 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:45,768 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:45,768 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 4a41b515-c69a-4906-b259-33b3af9bcf1c, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:45,769 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 4a41b515-c69a-4906-b259-33b3af9bcf1c, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:45,771 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 4a41b515-c69a-4906-b259-33b3af9bcf1c, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:46,166 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:46,167 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:46,167 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 3cc4fea4-fa4f-4178-ae19-51069bca986b, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:46,168 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 3cc4fea4-fa4f-4178-ae19-51069bca986b, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:46,170 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 3cc4fea4-fa4f-4178-ae19-51069bca986b, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:46,611 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:46,612 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 753008, 'BlobCount': 256}}
2025-11-21 17:41:46,612 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:46,613 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, cbcfd9ab-8c8a-4cb1-9b1d-0e816fe625ef, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:46,614 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, cbcfd9ab-8c8a-4cb1-9b1d-0e816fe625ef, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:46,616 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, cbcfd9ab-8c8a-4cb1-9b1d-0e816fe625ef, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:49,035 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:49,035 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:49,036 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 9c6a060a-e27a-43cc-b71d-ccc9a87d8a51, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:49,036 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 9c6a060a-e27a-43cc-b71d-ccc9a87d8a51, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:49,038 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 9c6a060a-e27a-43cc-b71d-ccc9a87d8a51, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:49,453 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:49,454 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:49,455 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 35157b91-1c18-41da-878a-accfc4642af0, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:49,456 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 35157b91-1c18-41da-878a-accfc4642af0, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:49,457 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 35157b91-1c18-41da-878a-accfc4642af0, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:49,902 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:49,903 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 1129888, 'BlobCount': 384}}
2025-11-21 17:41:49,903 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:49,904 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, fe37108e-31d9-4c86-bd9e-b28fdb1b7566, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:49,905 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, fe37108e-31d9-4c86-bd9e-b28fdb1b7566, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:49,910 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, fe37108e-31d9-4c86-bd9e-b28fdb1b7566, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:52,166 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:52,167 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:52,168 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, a455f2f6-6118-4ad3-86b2-e61eab31b423, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:52,169 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, a455f2f6-6118-4ad3-86b2-e61eab31b423, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:52,171 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, a455f2f6-6118-4ad3-86b2-e61eab31b423, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:52,614 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:52,615 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:52,616 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 450aafc6-e1f6-43cb-bb83-4a75cd51dd87, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:52,617 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 450aafc6-e1f6-43cb-bb83-4a75cd51dd87, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:52,619 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 450aafc6-e1f6-43cb-bb83-4a75cd51dd87, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:53,130 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:53,131 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1507072, 'BlobCount': 512}}
2025-11-21 17:41:53,132 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:53,132 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8ec3f300-b281-4ddf-baeb-9f2bfa99e7fa, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:53,133 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8ec3f300-b281-4ddf-baeb-9f2bfa99e7fa, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:53,140 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8ec3f300-b281-4ddf-baeb-9f2bfa99e7fa, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:55,541 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:55,542 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:55,543 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, f9014cb2-1791-430a-bea0-5f272e66542b, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:55,544 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, f9014cb2-1791-430a-bea0-5f272e66542b, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:55,545 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, f9014cb2-1791-430a-bea0-5f272e66542b, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:55,969 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:55,970 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:55,970 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 9d4b32aa-423f-4ec0-a098-0d55eed6636b, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:55,971 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 9d4b32aa-423f-4ec0-a098-0d55eed6636b, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:55,972 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 9d4b32aa-423f-4ec0-a098-0d55eed6636b, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:56,538 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:56,539 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1883936, 'BlobCount': 640}}
2025-11-21 17:41:56,540 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:56,541 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, eef665b8-e6a9-49b8-8681-d818dfa82f83, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:56,542 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, eef665b8-e6a9-49b8-8681-d818dfa82f83, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:56,602 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, eef665b8-e6a9-49b8-8681-d818dfa82f83, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:58,813 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:58,813 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:58,814 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, e876f586-0545-4b7d-99e7-3487c68bbfdb, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:58,815 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, e876f586-0545-4b7d-99e7-3487c68bbfdb, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:58,816 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, e876f586-0545-4b7d-99e7-3487c68bbfdb, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:59,136 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:59,137 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:59,137 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 89abd226-d406-417d-b898-b59c100662f9, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:59,138 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 89abd226-d406-417d-b898-b59c100662f9, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:59,139 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 89abd226-d406-417d-b898-b59c100662f9, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:59,761 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:59,762 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 2261136, 'BlobCount': 768}}
2025-11-21 17:41:59,762 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:59,763 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 49644a3f-acb8-4d73-a573-948e889af3d4, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:59,763 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 49644a3f-acb8-4d73-a573-948e889af3d4, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:59,766 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 49644a3f-acb8-4d73-a573-948e889af3d4, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:01,870 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:01,871 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:01,871 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, e6727e38-d777-4286-811c-58c541907e92, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:01,872 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, e6727e38-d777-4286-811c-58c541907e92, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:01,873 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, e6727e38-d777-4286-811c-58c541907e92, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:02,279 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:02,280 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:02,281 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, d293d237-2d2e-43b9-bff0-921fe466391c, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:02,282 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, d293d237-2d2e-43b9-bff0-921fe466391c, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:02,283 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, d293d237-2d2e-43b9-bff0-921fe466391c, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:02,922 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:02,923 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2638112, 'BlobCount': 896}}
2025-11-21 17:42:02,924 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:02,924 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, ed93180e-48cf-4e5d-a341-229a063ab40d, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:02,925 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, ed93180e-48cf-4e5d-a341-229a063ab40d, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:02,927 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, ed93180e-48cf-4e5d-a341-229a063ab40d, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:03,383 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 17:42:03,384 - DEBUG - ydb.connection.671694  - 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 17:42:03,397 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - context_resolve: Resolving endpoints for database /Root
2025-11-21 17:42:03,398 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ListEndpoints, ce3aa016-9b56-4736-84e8-cba309e3767d, localhost:21068): creating call state
2025-11-21 17:42:03,398 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ListEndpoints, ce3aa016-9b56-4736-84e8-cba309e3767d, localhost:21068): request = { database: "/Root" }
2025-11-21 17:42:03,413 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ListEndpoints, ce3aa016-9b56-4736-84e8-cba309e3767d, localhost:21068): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-zme4sewyte.auto.internal" port: 21068 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 17:42:03,414 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-zme4sewyte.auto.internal:21068, location 1, ssl: False>]>
2025-11-21 17:42:03,415 - DEBUG - ydb.connection.671694  - close: Closing channel for endpoint localhost:21068
2025-11-21 17:42:05,174 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:05,175 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:05,176 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8cf75d6f-9204-4670-a4cf-4ceedbec0d37, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:05,177 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8cf75d6f-9204-4670-a4cf-4ceedbec0d37, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:05,179 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8cf75d6f-9204-4670-a4cf-4ceedbec0d37, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:05,522 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:05,523 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:05,523 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, a8e6ff49-56f1-4da1-ba0c-669d3ec2a7a3, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:05,524 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, a8e6ff49-56f1-4da1-ba0c-669d3ec2a7a3, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:05,526 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, a8e6ff49-56f1-4da1-ba0c-669d3ec2a7a3, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:06,185 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:06,186 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 3015280, 'BlobCount': 1024}}
2025-11-21 17:42:06,187 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:06,188 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, cc4452ee-501c-4abd-a809-45ac8017598f, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:06,189 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, cc4452ee-501c-4abd-a809-45ac8017598f, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:06,190 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, cc4452ee-501c-4abd-a809-45ac8017598f, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:08,361 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:08,362 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:08,363 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 4dce1b9f-4d3c-4536-b2dc-d17553bb447f, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:08,364 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 4dce1b9f-4d3c-4536-b2dc-d17553bb447f, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:08,365 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 4dce1b9f-4d3c-4536-b2dc-d17553bb447f, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:08,639 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:08,640 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:08,641 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 690fa363-da71-48dd-9b20-026df43c2683, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:08,642 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 690fa363-da71-48dd-9b20-026df43c2683, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:08,644 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 690fa363-da71-48dd-9b20-026df43c2683, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:09,313 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:09,314 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 3392288, 'BlobCount': 1152}}
2025-11-21 17:42:09,315 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:09,315 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, aca530a3-c59d-4d6f-98e8-d6506761f764, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:09,317 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, aca530a3-c59d-4d6f-98e8-d6506761f764, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:09,318 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, aca530a3-c59d-4d6f-98e8-d6506761f764, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:11,382 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:11,383 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:11,384 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 589e74d1-246d-40ce-8505-9aa6c6ca74d9, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:11,384 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 589e74d1-246d-40ce-8505-9aa6c6ca74d9, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:11,386 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 589e74d1-246d-40ce-8505-9aa6c6ca74d9, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:11,725 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:11,726 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:11,727 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 0c126173-b87b-42c3-88ae-b6a41bfed3be, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:11,728 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 0c126173-b87b-42c3-88ae-b6a41bfed3be, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:11,730 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 0c126173-b87b-42c3-88ae-b6a41bfed3be, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:12,429 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:12,430 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3769600, 'BlobCount': 1280}}
2025-11-21 17:42:12,431 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:12,432 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8727f237-8883-41be-ba66-9e74cf6c5531, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:12,432 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8727f237-8883-41be-ba66-9e74cf6c5531, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_11_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:12,436 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8727f237-8883-41be-ba66-9e74cf6c5531, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:13,529 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:13,530 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:13,531 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 984aa679-4548-4f5b-bb3a-8b955920c583, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:13,532 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 984aa679-4548-4f5b-bb3a-8b955920c583, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:13,533 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 984aa679-4548-4f5b-bb3a-8b955920c583, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:13,816 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:13,817 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:13,818 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 0886394e-88ae-4ce5-a1a0-8a8b8be19847, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:13,819 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 0886394e-88ae-4ce5-a1a0-8a8b8be19847, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:13,821 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 0886394e-88ae-4ce5-a1a0-8a8b8be19847, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:14,130 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:14,131 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:14,131 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, ee0a9cf6-7e1e-41bd-8610-71241fcd4c2e, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:14,132 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, ee0a9cf6-7e1e-41bd-8610-71241fcd4c2e, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:14,134 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, ee0a9cf6-7e1e-41bd-8610-71241fcd4c2e, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:17,859 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:27,945 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:27,946 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 9a1bda61-b0e8-401f-a137-c555bd55d502, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:27,947 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 9a1bda61-b0e8-401f-a137-c555bd55d502, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:27,948 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 9a1bda61-b0e8-401f-a137-c555bd55d502, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:31,109 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:31,152 - INFO - root.671694  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1884800)
2025-11-21 17:42:31,153 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:31,153 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, ed318c0d-35de-4cc1-a8f9-bc05004bb354, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:31,154 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, ed318c0d-35de-4cc1-a8f9-bc05004bb354, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:31,155 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, ed318c0d-35de-4cc1-a8f9-bc05004bb354, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:31,446 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:31,447 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:31,447 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, bf7a7282-b50f-472a-af50-3f17ee2fa5ca, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:31,448 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, bf7a7282-b50f-472a-af50-3f17ee2fa5ca, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:31,451 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, bf7a7282-b50f-472a-af50-3f17ee2fa5ca, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:34,628 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:44,670 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:44,671 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, c1adea56-59f0-4107-b3ff-4e93768bf774, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:44,671 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, c1adea56-59f0-4107-b3ff-4e93768bf774, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:44,673 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, c1adea56-59f0-4107-b3ff-4e93768bf774, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:46,258 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:42:46,266 - INFO - root.671694  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1530600)
2025-11-21 17:42:46,266 - INFO - root.671694  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_11_compression` 8092160 / 1530600: 5.286920162027963
2025-11-21 17:42:46,268 - INFO - ya.test.671694  - pytest_runtest_teardown: Test teardown
2025-11-21 17:42:46,270 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(DeleteSession, ea2b7fe6-9c61-4aa5-9766-08f2d39e4422, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:42:46,270 - DEBUG - ydb.connection.671694  - _log_request: RpcState(DeleteSession, ea2b7fe6-9c61-4aa5-9766-08f2d39e4422, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" }
2025-11-21 17:42:46,276 - DEBUG - ydb.connection.671694  - _log_response: RpcState(DeleteSession, ea2b7fe6-9c61-4aa5-9766-08f2d39e4422, ghrun-zme4sewyte.auto.internal:21068): response = { status: SUCCESS }
2025-11-21 17:42:46,277 - DEBUG - ydb.query.pool.671694  - stop: All session were deleted.
2025-11-21 17:42:46,284 - DEBUG - ydb.topic.671694  - close: Close topic client
2025-11-21 17:42:46,284 - DEBUG - ydb.topic.671694  - close: Topic client was closed
2025-11-21 17:42:46,290 - DEBUG - ydb.connection.671694  - close: Closing channel for endpoint localhost:21068
2025-11-21 17:42:46,291 - DEBUG - ydb.connection.671694  - _rpc_error_handler: RpcState(AttachSession, a92e7525-3faa-4a79-ae65-f3df30b30ffd, ghrun-zme4sewyte.auto.internal:21068): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-21 17:42:46,292 - DEBUG - ydb.connection.671694  - close: Closing channel for endpoint ghrun-zme4sewyte.auto.internal:21068
2025-11-21 17:42:46,298 - INFO - ydb.pool.Discovery.671694  - run: Successfully terminated discovery process
2025-11-21 17:43:03,326 - INFO - ydb.tests.library.harness.kikimr_runner.671694  - stop: Stopped node localhost:21068/1
2025-11-21 17:43:03,330 - DEBUG - library.python.filelock.671694  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17374
2025-11-21 17:43:03,331 - DEBUG - library.python.filelock.671694  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16433
2025-11-21 17:43:03,331 - DEBUG - library.python.filelock.671694  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10334
2025-11-21 17:43:03,332 - DEBUG - library.python.filelock.671694  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21068
2025-11-21 17:43:03,332 - DEBUG - library.python.filelock.671694  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16874
