2025-11-21 12:13:13,744 - INFO - ya.test.1731450  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:13:13,745 - INFO - ya.test.1731450  - pytest_runtest_setup: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16]
2025-11-21 12:13:13,745 - INFO - ya.test.1731450  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:13:13,745 - INFO - ya.test.1731450  - pytest_runtest_setup: Test setup
2025-11-21 12:13:13,748 - INFO - ya.test.1731450  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16])
2025-11-21 12:13:13,749 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:13,750 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, d8864d30-4e70-42fd-8749-31e015150d74, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:13,750 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, d8864d30-4e70-42fd-8749-31e015150d74, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_16_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 16)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:13,753 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, d8864d30-4e70-42fd-8749-31e015150d74, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:17,791 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:17,792 - INFO - __tests__.alter_compression.1731450  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_16_compression created
2025-11-21 12:13:17,792 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:17,793 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 4bb8398e-ca92-4f45-90b3-0943b18f3c48, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:17,793 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 4bb8398e-ca92-4f45-90b3-0943b18f3c48, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:17,794 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 4bb8398e-ca92-4f45-90b3-0943b18f3c48, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:19,153 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:19,154 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:19,154 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 84d5d996-e2bd-46e8-889f-c474538fec7a, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:19,155 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 84d5d996-e2bd-46e8-889f-c474538fec7a, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:19,157 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 84d5d996-e2bd-46e8-889f-c474538fec7a, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:24,083 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:24,084 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:24,085 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, a2e0a72e-586e-4389-8108-21e2c32940be, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:24,086 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, a2e0a72e-586e-4389-8108-21e2c32940be, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:24,087 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, a2e0a72e-586e-4389-8108-21e2c32940be, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:25,241 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:25,242 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:25,243 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, dd61ee75-06ff-41a1-9b84-fbda83f856a8, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:25,244 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, dd61ee75-06ff-41a1-9b84-fbda83f856a8, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:25,246 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, dd61ee75-06ff-41a1-9b84-fbda83f856a8, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:27,367 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:27,368 - INFO - __tests__.alter_compression.1731450  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-21 12:13:27,369 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:27,370 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 1399235b-8d27-47ca-9015-828aca153eda, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:27,371 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 1399235b-8d27-47ca-9015-828aca153eda, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:27,372 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 1399235b-8d27-47ca-9015-828aca153eda, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:32,116 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:32,117 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:32,117 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 1265a7c0-8670-4069-967c-86689c81b9c6, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:32,119 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 1265a7c0-8670-4069-967c-86689c81b9c6, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:32,120 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 1265a7c0-8670-4069-967c-86689c81b9c6, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:32,501 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:32,502 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:32,503 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 1a097cfb-08d0-462b-b80d-833eedb933d1, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:32,504 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 1a097cfb-08d0-462b-b80d-833eedb933d1, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:32,505 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 1a097cfb-08d0-462b-b80d-833eedb933d1, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:33,129 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:33,130 - INFO - __tests__.alter_compression.1731450  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-21 12:13:33,130 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:33,131 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 369bcf75-2389-4aa8-86ad-cc1bcb37887a, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:33,132 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 369bcf75-2389-4aa8-86ad-cc1bcb37887a, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:33,184 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 369bcf75-2389-4aa8-86ad-cc1bcb37887a, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:37,555 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:37,556 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:37,557 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 34cd1c34-3bb0-4cb4-a9ef-7bd240ac9784, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:37,557 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 34cd1c34-3bb0-4cb4-a9ef-7bd240ac9784, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:37,559 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 34cd1c34-3bb0-4cb4-a9ef-7bd240ac9784, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:37,839 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:37,841 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:37,842 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 3a3733ca-9b93-4dbd-8922-bde0d8a6b993, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:37,843 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 3a3733ca-9b93-4dbd-8922-bde0d8a6b993, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:37,844 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 3a3733ca-9b93-4dbd-8922-bde0d8a6b993, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:38,260 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:38,260 - INFO - __tests__.alter_compression.1731450  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-21 12:13:38,261 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:38,262 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 63a26ddf-1198-43bd-b153-68eddf096607, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:38,263 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 63a26ddf-1198-43bd-b153-68eddf096607, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:38,268 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 63a26ddf-1198-43bd-b153-68eddf096607, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:43,056 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:43,057 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:43,058 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 1f948490-d14a-4ed9-b25d-e2f6c870bfa0, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:43,059 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 1f948490-d14a-4ed9-b25d-e2f6c870bfa0, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:43,061 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 1f948490-d14a-4ed9-b25d-e2f6c870bfa0, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:43,556 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:43,557 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:43,558 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 157162fa-cedc-406e-a891-5eaa1af2e0f5, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:43,559 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 157162fa-cedc-406e-a891-5eaa1af2e0f5, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:43,563 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 157162fa-cedc-406e-a891-5eaa1af2e0f5, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:44,256 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:44,257 - INFO - __tests__.alter_compression.1731450  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-21 12:13:44,257 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:44,258 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 554a8316-c924-4d6c-b220-5b5672ee7e75, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:44,259 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 554a8316-c924-4d6c-b220-5b5672ee7e75, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:44,261 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 554a8316-c924-4d6c-b220-5b5672ee7e75, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:49,112 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:49,113 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:49,114 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 1f8d1b33-129d-42a8-8be9-ad86afe547d4, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:49,115 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 1f8d1b33-129d-42a8-8be9-ad86afe547d4, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:49,116 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 1f8d1b33-129d-42a8-8be9-ad86afe547d4, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:49,397 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:49,398 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:49,399 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 140f4d7c-c48f-4655-bea7-d6cdfc4dc44b, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:49,399 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 140f4d7c-c48f-4655-bea7-d6cdfc4dc44b, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:49,401 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 140f4d7c-c48f-4655-bea7-d6cdfc4dc44b, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:49,877 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:49,878 - INFO - __tests__.alter_compression.1731450  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-21 12:13:49,878 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:49,879 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, a41f7c14-9363-4a6e-9c79-e8e48843ce27, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:49,883 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, a41f7c14-9363-4a6e-9c79-e8e48843ce27, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:49,884 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, a41f7c14-9363-4a6e-9c79-e8e48843ce27, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:54,131 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:54,131 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:54,132 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, e7c5bdd3-eaa7-4837-b6c1-d47d6de409db, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:54,133 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, e7c5bdd3-eaa7-4837-b6c1-d47d6de409db, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:54,135 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, e7c5bdd3-eaa7-4837-b6c1-d47d6de409db, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:54,383 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:54,384 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:54,385 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 5786f92f-6192-4e77-8ec1-8d9dde14a156, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:54,386 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 5786f92f-6192-4e77-8ec1-8d9dde14a156, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:54,388 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 5786f92f-6192-4e77-8ec1-8d9dde14a156, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:54,941 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:54,942 - INFO - __tests__.alter_compression.1731450  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-21 12:13:54,942 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:54,948 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 3f9ebb6b-8bc4-4fba-98c2-1484f8f2bf92, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:54,949 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 3f9ebb6b-8bc4-4fba-98c2-1484f8f2bf92, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:54,951 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 3f9ebb6b-8bc4-4fba-98c2-1484f8f2bf92, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:58,627 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1731450  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 12:13:58,628 - DEBUG - ydb.connection.1731450  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 12:13:58,662 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1731450  - context_resolve: Resolving endpoints for database /Root
2025-11-21 12:13:58,663 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ListEndpoints, 2d53abca-86ec-4d38-ba06-d499da5ed518, localhost:21862): creating call state
2025-11-21 12:13:58,663 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ListEndpoints, 2d53abca-86ec-4d38-ba06-d499da5ed518, localhost:21862): request = { database: "/Root" }
2025-11-21 12:13:58,669 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ListEndpoints, 2d53abca-86ec-4d38-ba06-d499da5ed518, localhost:21862): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ts5cnd6jre.auto.internal" port: 21862 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-21 12:13:58,670 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1731450  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ts5cnd6jre.auto.internal:21862, location 1, ssl: False>]>
2025-11-21 12:13:58,670 - DEBUG - ydb.connection.1731450  - close: Closing channel for endpoint localhost:21862
2025-11-21 12:13:59,720 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:59,721 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:13:59,721 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, cd5c6cbc-5c74-490a-8dc7-7778f07ff4dd, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:13:59,722 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, cd5c6cbc-5c74-490a-8dc7-7778f07ff4dd, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:59,723 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, cd5c6cbc-5c74-490a-8dc7-7778f07ff4dd, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:00,163 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:00,163 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:00,164 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, e8aed538-e4bb-4dca-9125-86836fc6ca4e, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:00,165 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, e8aed538-e4bb-4dca-9125-86836fc6ca4e, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:00,167 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, e8aed538-e4bb-4dca-9125-86836fc6ca4e, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:00,818 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:00,819 - INFO - __tests__.alter_compression.1731450  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-21 12:14:00,820 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:00,820 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 3c90f0b7-3052-4697-99ce-d9e41b931126, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:00,821 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 3c90f0b7-3052-4697-99ce-d9e41b931126, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:00,826 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 3c90f0b7-3052-4697-99ce-d9e41b931126, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:05,335 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:05,336 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:05,337 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, f13774d4-cf3a-466a-bf7f-f061a60ff230, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:05,338 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, f13774d4-cf3a-466a-bf7f-f061a60ff230, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:05,343 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, f13774d4-cf3a-466a-bf7f-f061a60ff230, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:05,667 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:05,668 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:05,669 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 468fce1e-8d44-4833-8ea9-49cbdc338a38, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:05,670 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 468fce1e-8d44-4833-8ea9-49cbdc338a38, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:05,672 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 468fce1e-8d44-4833-8ea9-49cbdc338a38, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:06,352 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:06,353 - INFO - __tests__.alter_compression.1731450  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-21 12:14:06,353 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:06,354 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 8c9d1d2a-6fe0-4abe-98ca-8600180894d0, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:06,355 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 8c9d1d2a-6fe0-4abe-98ca-8600180894d0, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:06,357 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 8c9d1d2a-6fe0-4abe-98ca-8600180894d0, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:10,576 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:10,577 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:10,578 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 3c3f80e6-2140-4a97-a7e5-06c0f4f211a2, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:10,579 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 3c3f80e6-2140-4a97-a7e5-06c0f4f211a2, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:10,580 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 3c3f80e6-2140-4a97-a7e5-06c0f4f211a2, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:10,928 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:10,929 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:10,929 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, f3e5c8bc-004b-4a0f-9ecd-90ebb8fa173d, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:10,930 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, f3e5c8bc-004b-4a0f-9ecd-90ebb8fa173d, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:10,932 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, f3e5c8bc-004b-4a0f-9ecd-90ebb8fa173d, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:11,772 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:11,773 - INFO - __tests__.alter_compression.1731450  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-21 12:14:11,773 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:11,774 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 54f12425-0b0f-4b4b-b7ab-ff5ba33bcd74, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:11,775 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 54f12425-0b0f-4b4b-b7ab-ff5ba33bcd74, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:11,777 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 54f12425-0b0f-4b4b-b7ab-ff5ba33bcd74, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:15,832 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:15,833 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:15,834 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 82eb627b-2035-41ee-a6c0-338794c185b1, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:15,835 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 82eb627b-2035-41ee-a6c0-338794c185b1, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:15,836 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 82eb627b-2035-41ee-a6c0-338794c185b1, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:16,089 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:16,090 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:16,090 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 43046366-d452-4e16-aebd-5d6bed68efa2, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:16,091 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 43046366-d452-4e16-aebd-5d6bed68efa2, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:16,092 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 43046366-d452-4e16-aebd-5d6bed68efa2, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:16,754 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:16,755 - INFO - __tests__.alter_compression.1731450  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-21 12:14:16,756 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:16,756 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, f427a72c-3e83-451f-8dba-7729dec441ac, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:16,757 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, f427a72c-3e83-451f-8dba-7729dec441ac, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:16,759 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, f427a72c-3e83-451f-8dba-7729dec441ac, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:18,350 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:18,351 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:18,352 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 874fe94d-f589-4786-818c-e4e41e359f9b, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:18,353 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 874fe94d-f589-4786-818c-e4e41e359f9b, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:18,354 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 874fe94d-f589-4786-818c-e4e41e359f9b, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:18,639 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:18,640 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:18,641 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 449d87f4-07dd-47af-a74c-2d180f06bf51, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:18,642 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 449d87f4-07dd-47af-a74c-2d180f06bf51, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:18,643 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 449d87f4-07dd-47af-a74c-2d180f06bf51, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:19,028 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:19,029 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:19,029 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 43c32726-ad12-497b-a4c0-6f89dbefb68f, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:19,031 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 43c32726-ad12-497b-a4c0-6f89dbefb68f, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:19,032 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 43c32726-ad12-497b-a4c0-6f89dbefb68f, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:22,228 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:32,251 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:32,252 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, d0bfd1e4-b78f-4740-ba26-7392a8506fe1, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:32,253 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, d0bfd1e4-b78f-4740-ba26-7392a8506fe1, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:32,255 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, d0bfd1e4-b78f-4740-ba26-7392a8506fe1, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:33,812 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:43,822 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:43,823 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, bbdf9d6d-1715-4a9e-b3d8-e81254e1f6a5, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:43,824 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, bbdf9d6d-1715-4a9e-b3d8-e81254e1f6a5, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:43,826 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, bbdf9d6d-1715-4a9e-b3d8-e81254e1f6a5, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:44,651 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:44,656 - INFO - root.1731450  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1497984)
2025-11-21 12:14:44,657 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:44,657 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 7eb74088-b883-49cb-96a7-3da03dff09d0, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:44,658 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 7eb74088-b883-49cb-96a7-3da03dff09d0, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:44,660 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 7eb74088-b883-49cb-96a7-3da03dff09d0, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:44,929 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:44,930 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:44,930 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, 1ccdaa78-7acd-485e-9f72-04aaa9fe4793, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:44,931 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, 1ccdaa78-7acd-485e-9f72-04aaa9fe4793, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:44,932 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, 1ccdaa78-7acd-485e-9f72-04aaa9fe4793, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:45,568 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:55,623 - DEBUG - ydb.query.pool.1731450  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:55,624 - DEBUG - ydb.connection.1731450  - _prepare_call: RpcState(ExecuteQuery, b8da5fb3-83de-45c2-bf10-d4c95e914de0, ghrun-ts5cnd6jre.auto.internal:21862): creating call state
2025-11-21 12:14:55,625 - DEBUG - ydb.connection.1731450  - _log_request: RpcState(ExecuteQuery, b8da5fb3-83de-45c2-bf10-d4c95e914de0, ghrun-ts5cnd6jre.auto.internal:21862): request = { session_id: "ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:55,631 - DEBUG - ydb.connection.1731450  - _log_response: RpcState(ExecuteQuery, b8da5fb3-83de-45c2-bf10-d4c95e914de0, ghrun-ts5cnd6jre.auto.internal:21862): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:56,404 - DEBUG - ydb.query.pool.1731450  - release: Session returned to queue: ydb://session/3?node_id=1&id=NGMxZGUyNDYtZWE3NjU0NWEtMTUwMjk4ZmYtOTg4OTEwOTQ=
2025-11-21 12:14:56,409 - INFO - root.1731450  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1497984)
2025-11-21 12:14:56,410 - INFO - root.1731450  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_16_compression` 8092160 / 1497984: 5.402033666581218
2025-11-21 12:14:56,412 - INFO - ya.test.1731450  - pytest_runtest_teardown: Test teardown
