2025-10-16 17:21:30,145 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:21:30,145 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19]
2025-10-16 17:21:30,145 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:21:30,146 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 17:21:30,148 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19])
2025-10-16 17:21:30,148 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:30,149 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bb55148e-ba8a-4019-9729-ae7c700cf456, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:30,150 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bb55148e-ba8a-4019-9729-ae7c700cf456, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_19_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 19)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:30,152 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bb55148e-ba8a-4019-9729-ae7c700cf456, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:33,913 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:33,913 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_19_compression created
2025-10-16 17:21:33,914 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:33,914 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4a0a2cc5-c065-49b7-95c6-f7e0ab3d5329, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:33,915 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4a0a2cc5-c065-49b7-95c6-f7e0ab3d5329, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:33,922 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4a0a2cc5-c065-49b7-95c6-f7e0ab3d5329, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:35,150 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:35,151 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:35,151 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09cfeeba-ee99-4e27-b5a1-4c56a49705b3, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:35,152 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09cfeeba-ee99-4e27-b5a1-4c56a49705b3, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:35,153 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09cfeeba-ee99-4e27-b5a1-4c56a49705b3, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:41,332 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:41,333 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:41,334 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9cf9af84-708d-4ce2-aec5-832a197b7ea1, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:41,335 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9cf9af84-708d-4ce2-aec5-832a197b7ea1, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:41,363 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9cf9af84-708d-4ce2-aec5-832a197b7ea1, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:42,666 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:42,667 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:42,668 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed84e6d2-819a-47d1-b725-da560a782072, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:42,668 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed84e6d2-819a-47d1-b725-da560a782072, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:42,670 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed84e6d2-819a-47d1-b725-da560a782072, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:44,949 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:44,950 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-10-16 17:21:44,951 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:44,951 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bf1752b5-04c6-44a7-b286-0a86b7f7b30a, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:44,952 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bf1752b5-04c6-44a7-b286-0a86b7f7b30a, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:44,957 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bf1752b5-04c6-44a7-b286-0a86b7f7b30a, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:50,808 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:50,809 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:50,810 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6c5c5720-f853-4903-a224-2fd0658edc60, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:50,810 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6c5c5720-f853-4903-a224-2fd0658edc60, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:50,811 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6c5c5720-f853-4903-a224-2fd0658edc60, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:51,213 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:51,214 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:51,214 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 719f4b51-fe4a-4a03-b4b6-67514362e5d9, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:51,215 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 719f4b51-fe4a-4a03-b4b6-67514362e5d9, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:51,218 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 719f4b51-fe4a-4a03-b4b6-67514362e5d9, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:51,888 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:51,889 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-10-16 17:21:51,889 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:51,890 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 744e8c5c-0c8f-481d-8edf-55614c58d012, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:51,891 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 744e8c5c-0c8f-481d-8edf-55614c58d012, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:51,892 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 744e8c5c-0c8f-481d-8edf-55614c58d012, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:57,298 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:57,299 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:57,300 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ea61bcd-e393-43f4-8c30-54438b59bd36, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:57,301 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ea61bcd-e393-43f4-8c30-54438b59bd36, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:57,303 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ea61bcd-e393-43f4-8c30-54438b59bd36, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:57,843 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:57,844 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:57,845 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3381a620-27f0-4113-b9dd-6ee127a734d8, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:57,846 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3381a620-27f0-4113-b9dd-6ee127a734d8, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:57,850 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3381a620-27f0-4113-b9dd-6ee127a734d8, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:58,603 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:58,604 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-10-16 17:21:58,605 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:58,606 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9387c611-d5b4-43e7-91f6-3610bd7f832f, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:58,608 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9387c611-d5b4-43e7-91f6-3610bd7f832f, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:58,609 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9387c611-d5b4-43e7-91f6-3610bd7f832f, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:03,859 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:03,860 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:03,861 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9010a2fd-1d3e-4fd4-8258-a0b05f581fa9, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:03,862 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9010a2fd-1d3e-4fd4-8258-a0b05f581fa9, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:03,866 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9010a2fd-1d3e-4fd4-8258-a0b05f581fa9, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:04,241 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:04,242 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:04,243 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 00b37529-8226-48ab-b9ab-a4683670b6c7, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:04,244 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 00b37529-8226-48ab-b9ab-a4683670b6c7, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:04,249 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 00b37529-8226-48ab-b9ab-a4683670b6c7, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:04,797 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:04,798 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 225}}, blobs: {'__DEFAULT': {'Portions': 225, 'BlobSize': 1279560, 'BlobCount': 504}}
2025-10-16 17:22:04,799 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:04,799 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d92b2a3b-711e-4785-a437-a0d0b485e809, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:04,800 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d92b2a3b-711e-4785-a437-a0d0b485e809, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:04,802 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d92b2a3b-711e-4785-a437-a0d0b485e809, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:09,395 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:09,396 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:09,397 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a88cd64e-f539-4837-ac8d-0449867dae59, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:09,398 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a88cd64e-f539-4837-ac8d-0449867dae59, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:09,400 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a88cd64e-f539-4837-ac8d-0449867dae59, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:09,700 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:09,701 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:09,701 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed7e55a4-7005-48cd-94d9-82b13f432ed5, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:09,702 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed7e55a4-7005-48cd-94d9-82b13f432ed5, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:09,703 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed7e55a4-7005-48cd-94d9-82b13f432ed5, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:10,197 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:10,198 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 277}}, blobs: {'__DEFAULT': {'Portions': 277, 'BlobSize': 1596448, 'BlobCount': 616}}
2025-10-16 17:22:10,198 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:10,199 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ffd49e97-ba20-4b53-a696-1912000c75ea, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:10,200 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ffd49e97-ba20-4b53-a696-1912000c75ea, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:10,205 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ffd49e97-ba20-4b53-a696-1912000c75ea, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:14,589 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:14,590 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:14,590 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd2c9c0a-17ce-4a3a-87f0-0d9a1d43cc7f, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:14,591 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd2c9c0a-17ce-4a3a-87f0-0d9a1d43cc7f, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:14,593 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd2c9c0a-17ce-4a3a-87f0-0d9a1d43cc7f, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:14,938 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:14,939 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:14,939 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d38a2b8c-37b9-438e-90c7-962542da9d53, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:14,940 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d38a2b8c-37b9-438e-90c7-962542da9d53, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:14,941 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d38a2b8c-37b9-438e-90c7-962542da9d53, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:15,401 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:15,402 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 325}}, blobs: {'__DEFAULT': {'Portions': 325, 'BlobSize': 1913320, 'BlobCount': 720}}
2025-10-16 17:22:15,402 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:15,403 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d4c80ab8-d96e-4f3c-b436-dbe81eb9221e, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:15,404 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d4c80ab8-d96e-4f3c-b436-dbe81eb9221e, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:15,405 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d4c80ab8-d96e-4f3c-b436-dbe81eb9221e, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:20,061 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:20,061 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:20,062 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d59af1ea-a9f8-48a8-9d7f-b11df2473fec, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:20,063 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d59af1ea-a9f8-48a8-9d7f-b11df2473fec, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:20,064 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d59af1ea-a9f8-48a8-9d7f-b11df2473fec, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:20,400 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:20,401 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:20,402 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bdf892b4-1d8e-40c6-93e8-33bb2a74acad, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:20,403 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bdf892b4-1d8e-40c6-93e8-33bb2a74acad, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:20,406 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bdf892b4-1d8e-40c6-93e8-33bb2a74acad, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:20,945 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:20,946 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 379}}, blobs: {'__DEFAULT': {'Portions': 379, 'BlobSize': 2230088, 'BlobCount': 832}}
2025-10-16 17:22:20,947 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:20,947 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbfc63e8-260f-4fe3-b7df-55a36a3976a7, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:20,948 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbfc63e8-260f-4fe3-b7df-55a36a3976a7, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:20,949 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbfc63e8-260f-4fe3-b7df-55a36a3976a7, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:25,288 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:25,289 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:25,290 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f618a7d4-6ff3-4326-a933-a822fe807100, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:25,290 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f618a7d4-6ff3-4326-a933-a822fe807100, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:25,292 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f618a7d4-6ff3-4326-a933-a822fe807100, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:25,537 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:25,538 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:25,539 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 453b8ed9-bca3-4cd0-b5f8-3389e41c712d, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:25,540 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 453b8ed9-bca3-4cd0-b5f8-3389e41c712d, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:25,541 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 453b8ed9-bca3-4cd0-b5f8-3389e41c712d, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:26,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:26,137 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 407}}, blobs: {'__DEFAULT': {'Portions': 407, 'BlobSize': 2540728, 'BlobCount': 900}}
2025-10-16 17:22:26,138 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:26,138 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a0cc98f-b092-490a-95ec-25d6b4e031ce, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:26,139 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a0cc98f-b092-490a-95ec-25d6b4e031ce, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:26,140 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a0cc98f-b092-490a-95ec-25d6b4e031ce, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:29,045 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:22:29,046 - 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-10-16 17:22:29,076 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:22:29,077 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 95a545ec-111a-4178-9e10-1540aa60034d, localhost:21181): creating call state
2025-10-16 17:22:29,078 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 95a545ec-111a-4178-9e10-1540aa60034d, localhost:21181): request = { database: "/Root" }
2025-10-16 17:22:29,083 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 95a545ec-111a-4178-9e10-1540aa60034d, localhost:21181): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 21181 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-10-16 17:22:29,084 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:21181, location 1, ssl: False>]>
2025-10-16 17:22:29,089 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21181
2025-10-16 17:22:30,461 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:30,461 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:30,462 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c37133cf-bc3c-442e-a06b-480b1a72e01e, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:30,463 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c37133cf-bc3c-442e-a06b-480b1a72e01e, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:30,464 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c37133cf-bc3c-442e-a06b-480b1a72e01e, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:30,806 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:30,807 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:30,808 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c893ddb3-9374-4cc9-a314-097e6f85e70b, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:30,809 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c893ddb3-9374-4cc9-a314-097e6f85e70b, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:30,817 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c893ddb3-9374-4cc9-a314-097e6f85e70b, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:31,369 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:31,370 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 415}}, blobs: {'__DEFAULT': {'Portions': 415, 'BlobSize': 2845096, 'BlobCount': 932}}
2025-10-16 17:22:31,370 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:31,371 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 158a197f-6b31-4c65-a00c-f439f6fe9074, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:31,372 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 158a197f-6b31-4c65-a00c-f439f6fe9074, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:31,381 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 158a197f-6b31-4c65-a00c-f439f6fe9074, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:36,134 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:36,135 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:36,136 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0328ae24-1bf6-4de1-b4ee-397fdca541fe, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:36,137 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0328ae24-1bf6-4de1-b4ee-397fdca541fe, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:36,139 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0328ae24-1bf6-4de1-b4ee-397fdca541fe, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:36,834 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:36,835 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:36,835 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22cae30f-6259-43c1-945b-eaff0fffc26c, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:36,836 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22cae30f-6259-43c1-945b-eaff0fffc26c, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:36,838 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22cae30f-6259-43c1-945b-eaff0fffc26c, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:37,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:37,767 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 1014005, 'Portions': 424}}, blobs: {'__DEFAULT': {'Portions': 416, 'BlobSize': 3190488, 'BlobCount': 952}}
2025-10-16 17:22:37,767 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:37,768 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 44e4e275-8c3f-44c1-a88f-c406b309bd79, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:37,768 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 44e4e275-8c3f-44c1-a88f-c406b309bd79, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:37,771 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 44e4e275-8c3f-44c1-a88f-c406b309bd79, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:39,558 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:39,559 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:39,560 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c47f4312-5917-4c84-8a0b-a3978b5d1d7b, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:39,561 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c47f4312-5917-4c84-8a0b-a3978b5d1d7b, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:39,569 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c47f4312-5917-4c84-8a0b-a3978b5d1d7b, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:40,014 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:40,015 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:40,016 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5a3d65a-b088-4cba-a337-d7061ad070cb, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:40,017 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5a3d65a-b088-4cba-a337-d7061ad070cb, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:40,024 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5a3d65a-b088-4cba-a337-d7061ad070cb, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:40,290 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:40,291 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:40,292 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 21b49fca-042e-4bc6-9ddd-753f86805d3e, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:40,292 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 21b49fca-042e-4bc6-9ddd-753f86805d3e, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:40,301 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 21b49fca-042e-4bc6-9ddd-753f86805d3e, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:43,498 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:53,521 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:53,522 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7d01adc-f665-4296-8ffc-63e975963262, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:53,523 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7d01adc-f665-4296-8ffc-63e975963262, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:53,525 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7d01adc-f665-4296-8ffc-63e975963262, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:56,122 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:56,146 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1536520)
2025-10-16 17:22:56,147 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:56,149 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a516fe3-c91e-442d-8aa3-074b5d27232c, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:56,150 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a516fe3-c91e-442d-8aa3-074b5d27232c, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:56,151 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a516fe3-c91e-442d-8aa3-074b5d27232c, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:56,526 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:56,526 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:22:56,527 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb7f45f6-cacb-4630-bd97-7189bb8bfdcf, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:22:56,528 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb7f45f6-cacb-4630-bd97-7189bb8bfdcf, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:22:56,530 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb7f45f6-cacb-4630-bd97-7189bb8bfdcf, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:22:58,920 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:23:08,944 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:23:08,945 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f353b38d-7f66-4ace-907b-25151894cb74, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:23:08,946 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f353b38d-7f66-4ace-907b-25151894cb74, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:23:08,947 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f353b38d-7f66-4ace-907b-25151894cb74, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:23:11,448 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:23:11,470 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1536520)
2025-10-16 17:23:11,471 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_19_compression` 8073584 / 1536520: 5.254460729440554
2025-10-16 17:23:11,473 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-16 17:23:11,474 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, a4d49223-a4a9-434c-a14a-ccb75e5d7e20, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:23:11,475 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, a4d49223-a4a9-434c-a14a-ccb75e5d7e20, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" }
2025-10-16 17:23:11,479 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, a4d49223-a4a9-434c-a14a-ccb75e5d7e20, ghrun-2kszfvc6cm.auto.internal:21181): response = { status: SUCCESS }
2025-10-16 17:23:11,480 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-16 17:23:11,481 - DEBUG - ydb.topic - close: Close topic client
2025-10-16 17:23:11,481 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-16 17:23:11,481 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21181
2025-10-16 17:23:11,484 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-2kszfvc6cm.auto.internal:21181
2025-10-16 17:23:11,489 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-16 17:23:11,485 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, a512dda9-20cf-475e-8b7e-5e0d548c7083, ghrun-2kszfvc6cm.auto.internal:21181): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-10-16 17:23:23,521 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:21181/1
2025-10-16 17:23:23,524 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24978
2025-10-16 17:23:23,525 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9312
2025-10-16 17:23:23,525 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9734
2025-10-16 17:23:23,532 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21181
2025-10-16 17:23:23,532 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10249
