2025-11-19 11:22:12,218 - INFO - ya.test.420202  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:22:12,218 - INFO - ya.test.420202  - pytest_runtest_setup: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10]
2025-11-19 11:22:12,219 - INFO - ya.test.420202  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:22:12,219 - INFO - ya.test.420202  - pytest_runtest_setup: Test setup
2025-11-19 11:22:12,222 - INFO - ya.test.420202  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10])
2025-11-19 11:22:12,222 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:12,223 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 7c33b1de-3295-44b9-acd0-41b517040d4c, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:12,237 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 7c33b1de-3295-44b9-acd0-41b517040d4c, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_10_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 10)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:12,239 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 7c33b1de-3295-44b9-acd0-41b517040d4c, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:19,564 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:19,565 - INFO - __tests__.alter_compression.420202  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_10_compression created
2025-11-19 11:22:19,566 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:19,566 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 5d7def22-cf9b-4c1f-80ea-867a1b9d8ab7, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:19,567 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 5d7def22-cf9b-4c1f-80ea-867a1b9d8ab7, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_10_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:19,571 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 5d7def22-cf9b-4c1f-80ea-867a1b9d8ab7, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:21,342 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:21,342 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:21,343 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, edf53422-2612-4d83-a342-28519c8e1c7a, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:21,344 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, edf53422-2612-4d83-a342-28519c8e1c7a, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:21,347 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, edf53422-2612-4d83-a342-28519c8e1c7a, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:24,254 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:24,255 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:24,256 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 772ea5aa-26d6-4e1d-9162-abf1070b5d30, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:24,256 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 772ea5aa-26d6-4e1d-9162-abf1070b5d30, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:24,257 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 772ea5aa-26d6-4e1d-9162-abf1070b5d30, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:26,061 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:26,062 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:26,062 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 90f6ccc2-c937-4138-b431-84cf1f132e34, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:26,063 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 90f6ccc2-c937-4138-b431-84cf1f132e34, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:26,066 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 90f6ccc2-c937-4138-b431-84cf1f132e34, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:30,023 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:30,029 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318048, 'BlobCount': 128}}
2025-11-19 11:22:30,030 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:30,030 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 1f5607b9-5173-4380-9ffe-2910d69734bd, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:30,034 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 1f5607b9-5173-4380-9ffe-2910d69734bd, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:30,043 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 1f5607b9-5173-4380-9ffe-2910d69734bd, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:32,715 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:32,715 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:32,716 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 776a8459-5dfe-4c4b-abe4-251bc6640c7a, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:32,717 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 776a8459-5dfe-4c4b-abe4-251bc6640c7a, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:32,719 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 776a8459-5dfe-4c4b-abe4-251bc6640c7a, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:33,217 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:33,218 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:33,219 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 348bfa82-b2a7-4351-aa0b-69eec466ee1b, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:33,220 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 348bfa82-b2a7-4351-aa0b-69eec466ee1b, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:33,226 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 348bfa82-b2a7-4351-aa0b-69eec466ee1b, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:33,858 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:33,858 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637344, 'BlobCount': 256}}
2025-11-19 11:22:33,859 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:33,860 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 32d2ed28-ed68-4e98-9607-10d42ab930cf, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:33,861 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 32d2ed28-ed68-4e98-9607-10d42ab930cf, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:33,862 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 32d2ed28-ed68-4e98-9607-10d42ab930cf, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:36,211 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:36,212 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:36,213 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 19b6ca79-cfab-46d1-851d-42e8baf3ea0c, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:36,214 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 19b6ca79-cfab-46d1-851d-42e8baf3ea0c, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:36,216 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 19b6ca79-cfab-46d1-851d-42e8baf3ea0c, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:36,644 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:36,645 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:36,646 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 43850bc3-a101-4085-b5a3-1b522def00e4, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:36,647 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 43850bc3-a101-4085-b5a3-1b522def00e4, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:36,657 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 43850bc3-a101-4085-b5a3-1b522def00e4, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:37,680 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:37,681 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956384, 'BlobCount': 384}}
2025-11-19 11:22:37,682 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:37,682 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 60b1caaf-93a1-4b73-b1c6-d4e9a5ce2d1e, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:37,683 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 60b1caaf-93a1-4b73-b1c6-d4e9a5ce2d1e, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:37,688 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 60b1caaf-93a1-4b73-b1c6-d4e9a5ce2d1e, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:40,402 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:40,403 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:40,403 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 56d3fef8-0854-4bfe-820a-479e86fe4ecb, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:40,404 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 56d3fef8-0854-4bfe-820a-479e86fe4ecb, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:40,406 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 56d3fef8-0854-4bfe-820a-479e86fe4ecb, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:40,884 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:40,885 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:40,886 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 7ff29dca-4208-4f4f-8dda-bb09ec10afaf, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:40,887 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 7ff29dca-4208-4f4f-8dda-bb09ec10afaf, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:40,888 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 7ff29dca-4208-4f4f-8dda-bb09ec10afaf, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:41,920 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:41,921 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275744, 'BlobCount': 512}}
2025-11-19 11:22:41,921 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:41,922 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 6b99804e-3645-4335-9444-0729312ed463, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:41,923 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 6b99804e-3645-4335-9444-0729312ed463, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:41,932 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 6b99804e-3645-4335-9444-0729312ed463, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:44,464 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:44,465 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:44,466 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, a9b5bc66-fdac-4cd6-90e2-c304930a6cc3, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:44,467 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, a9b5bc66-fdac-4cd6-90e2-c304930a6cc3, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:44,469 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, a9b5bc66-fdac-4cd6-90e2-c304930a6cc3, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:45,118 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:45,119 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:45,120 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 7c3be82a-467e-4605-bff1-170ee92a2867, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:45,121 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 7c3be82a-467e-4605-bff1-170ee92a2867, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:45,126 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 7c3be82a-467e-4605-bff1-170ee92a2867, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:46,258 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:46,259 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594704, 'BlobCount': 640}}
2025-11-19 11:22:46,259 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:46,260 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 72d6da9f-2c61-4acc-a9e0-e8a481ac0cee, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:46,261 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 72d6da9f-2c61-4acc-a9e0-e8a481ac0cee, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:46,263 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 72d6da9f-2c61-4acc-a9e0-e8a481ac0cee, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:48,968 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:48,969 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:48,970 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, ea6e4605-4bc2-4693-a5ab-4d09225c7620, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:48,971 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, ea6e4605-4bc2-4693-a5ab-4d09225c7620, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:48,972 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, ea6e4605-4bc2-4693-a5ab-4d09225c7620, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:49,590 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:49,591 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:49,592 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 5bb32a55-d3d9-4174-83ff-5255032c679c, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:49,593 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 5bb32a55-d3d9-4174-83ff-5255032c679c, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:49,598 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 5bb32a55-d3d9-4174-83ff-5255032c679c, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:50,526 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:50,527 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913888, 'BlobCount': 768}}
2025-11-19 11:22:50,527 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:50,528 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, b42fa517-bafb-43e4-b557-f81bd229fd1a, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:50,529 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, b42fa517-bafb-43e4-b557-f81bd229fd1a, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:50,534 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, b42fa517-bafb-43e4-b557-f81bd229fd1a, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:53,684 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:53,685 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:53,686 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 2e3f8e24-a602-485d-9be7-19caf4ea3688, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:53,687 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 2e3f8e24-a602-485d-9be7-19caf4ea3688, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:53,689 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 2e3f8e24-a602-485d-9be7-19caf4ea3688, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:54,414 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:54,415 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:54,416 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, b16dd8cb-5bbe-4f96-aba3-d31f2db82173, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:54,417 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, b16dd8cb-5bbe-4f96-aba3-d31f2db82173, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:54,429 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, b16dd8cb-5bbe-4f96-aba3-d31f2db82173, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:56,094 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:56,095 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233024, 'BlobCount': 896}}
2025-11-19 11:22:56,095 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:56,096 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 51de2796-2c22-4f90-8ca3-a20a20984854, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:56,097 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 51de2796-2c22-4f90-8ca3-a20a20984854, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:56,109 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 51de2796-2c22-4f90-8ca3-a20a20984854, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:56,788 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.420202  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:22:56,789 - DEBUG - ydb.connection.420202  - 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-19 11:22:56,839 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.420202  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:22:56,840 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ListEndpoints, e4124f5e-652d-4860-8c39-d3548d75472e, localhost:8816): creating call state
2025-11-19 11:22:56,841 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ListEndpoints, e4124f5e-652d-4860-8c39-d3548d75472e, localhost:8816): request = { database: "/Root" }
2025-11-19 11:22:56,850 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ListEndpoints, e4124f5e-652d-4860-8c39-d3548d75472e, localhost:8816): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 8816 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-19 11:22:56,851 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.420202  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:8816, location 1, ssl: False>]>
2025-11-19 11:22:56,852 - DEBUG - ydb.connection.420202  - close: Closing channel for endpoint localhost:8816
2025-11-19 11:22:59,222 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:59,222 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:22:59,223 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, d54d12b2-9fb0-4c68-9887-9c2872e801e6, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:22:59,224 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, d54d12b2-9fb0-4c68-9887-9c2872e801e6, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:59,226 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, d54d12b2-9fb0-4c68-9887-9c2872e801e6, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:00,100 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:00,101 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:00,102 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, abf1ed54-4e39-4bed-b236-c36ef81f183a, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:00,103 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, abf1ed54-4e39-4bed-b236-c36ef81f183a, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:00,116 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, abf1ed54-4e39-4bed-b236-c36ef81f183a, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:01,403 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:01,404 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552512, 'BlobCount': 1024}}
2025-11-19 11:23:01,404 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:01,405 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, ac03fc8b-285c-4879-8a45-bc83ff939a0f, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:01,406 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, ac03fc8b-285c-4879-8a45-bc83ff939a0f, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:01,429 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, ac03fc8b-285c-4879-8a45-bc83ff939a0f, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:04,998 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:04,998 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:04,999 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, b0336ca2-68d8-420c-a8c5-e5a55e0d4941, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:05,000 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, b0336ca2-68d8-420c-a8c5-e5a55e0d4941, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:05,005 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, b0336ca2-68d8-420c-a8c5-e5a55e0d4941, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:05,596 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:05,596 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:05,597 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 819dc15b-f8d5-4840-84f8-81d4103a1d58, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:05,598 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 819dc15b-f8d5-4840-84f8-81d4103a1d58, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:05,599 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 819dc15b-f8d5-4840-84f8-81d4103a1d58, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:06,857 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:06,857 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871520, 'BlobCount': 1152}}
2025-11-19 11:23:06,858 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:06,858 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, d293732f-c944-4ecb-a0c2-b6ffbacd4616, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:06,859 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, d293732f-c944-4ecb-a0c2-b6ffbacd4616, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:06,861 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, d293732f-c944-4ecb-a0c2-b6ffbacd4616, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:10,130 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:10,131 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:10,132 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 9655dd00-822a-44d4-a3da-ebbd44a55dd5, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:10,140 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 9655dd00-822a-44d4-a3da-ebbd44a55dd5, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:10,142 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 9655dd00-822a-44d4-a3da-ebbd44a55dd5, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:10,792 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:10,793 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:10,794 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, d4500527-b714-4ff7-85cd-815d0d47edee, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:10,795 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, d4500527-b714-4ff7-85cd-815d0d47edee, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:10,799 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, d4500527-b714-4ff7-85cd-815d0d47edee, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:12,622 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:12,626 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191024, 'BlobCount': 1280}}
2025-11-19 11:23:12,626 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:12,627 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 0d1c103c-09ca-4a22-9a50-7c346c4cd30e, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:12,628 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 0d1c103c-09ca-4a22-9a50-7c346c4cd30e, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_10_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:12,629 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 0d1c103c-09ca-4a22-9a50-7c346c4cd30e, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:15,129 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:15,130 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:15,131 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, eee0e985-1b95-4d77-bb68-86f8468aa28e, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:15,132 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, eee0e985-1b95-4d77-bb68-86f8468aa28e, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:15,143 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, eee0e985-1b95-4d77-bb68-86f8468aa28e, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:15,605 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:15,605 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:15,606 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 8e51ff18-dee7-4b37-9b88-3d51f4ffd1b8, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:15,607 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 8e51ff18-dee7-4b37-9b88-3d51f4ffd1b8, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:15,609 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 8e51ff18-dee7-4b37-9b88-3d51f4ffd1b8, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:16,023 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:16,024 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:16,024 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 0dfb8f87-6c6b-4714-a22c-03e3d5e428c0, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:16,025 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 0dfb8f87-6c6b-4714-a22c-03e3d5e428c0, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:16,034 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 0dfb8f87-6c6b-4714-a22c-03e3d5e428c0, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:25,751 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:35,800 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:35,801 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, f521c5bf-9ee5-46aa-a69d-5e0c45392f5b, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:35,802 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, f521c5bf-9ee5-46aa-a69d-5e0c45392f5b, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:35,803 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, f521c5bf-9ee5-46aa-a69d-5e0c45392f5b, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:40,249 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:50,268 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:23:50,273 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 49fd4b04-e591-4486-883a-d4452763e8a6, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:23:50,274 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 49fd4b04-e591-4486-883a-d4452763e8a6, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:50,276 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 49fd4b04-e591-4486-883a-d4452763e8a6, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:51,850 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:01,857 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:01,858 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, ca42a23a-221e-4098-a8ca-c3405cc5c679, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:01,859 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, ca42a23a-221e-4098-a8ca-c3405cc5c679, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:01,862 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, ca42a23a-221e-4098-a8ca-c3405cc5c679, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:03,425 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:03,429 - INFO - root.420202  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1465240)
2025-11-19 11:24:03,429 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:03,430 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, b0c1d818-8d22-4d9a-8f2b-3137b646eebb, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:03,431 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, b0c1d818-8d22-4d9a-8f2b-3137b646eebb, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:03,436 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, b0c1d818-8d22-4d9a-8f2b-3137b646eebb, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:03,892 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:03,893 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:03,894 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, ef2601ec-6177-45cd-9044-f1db97b7a603, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:03,895 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, ef2601ec-6177-45cd-9044-f1db97b7a603, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:03,900 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, ef2601ec-6177-45cd-9044-f1db97b7a603, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:05,294 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:13,352 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.420202  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:24:13,353 - DEBUG - ydb.connection.420202  - 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-19 11:24:13,392 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.420202  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:24:13,393 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ListEndpoints, 71386f7e-4744-408b-99e5-0212e5d1f0dc, localhost:8816): creating call state
2025-11-19 11:24:13,394 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ListEndpoints, 71386f7e-4744-408b-99e5-0212e5d1f0dc, localhost:8816): request = { database: "/Root" }
2025-11-19 11:24:13,410 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ListEndpoints, 71386f7e-4744-408b-99e5-0212e5d1f0dc, localhost:8816): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 8816 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-19 11:24:13,412 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.420202  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:8816, location 1, ssl: False>]>
2025-11-19 11:24:13,414 - DEBUG - ydb.connection.420202  - close: Closing channel for endpoint localhost:8816
2025-11-19 11:24:15,300 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:15,301 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 9020a0b5-bcc7-473a-b92f-286e7b905d20, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:15,302 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 9020a0b5-bcc7-473a-b92f-286e7b905d20, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:15,304 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 9020a0b5-bcc7-473a-b92f-286e7b905d20, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:16,778 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:16,788 - INFO - root.420202  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1465240)
2025-11-19 11:24:16,788 - INFO - root.420202  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_10_compression` 8092160 / 1465240: 5.522753951571073
2025-11-19 11:24:16,794 - INFO - ya.test.420202  - pytest_runtest_teardown: Test teardown
