2025-11-19 11:24:16,803 - INFO - ya.test.420202  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:24:16,804 - INFO - ya.test.420202  - pytest_runtest_setup: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11]
2025-11-19 11:24:16,805 - INFO - ya.test.420202  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:24:16,806 - INFO - ya.test.420202  - pytest_runtest_setup: Test setup
2025-11-19 11:24:16,808 - INFO - ya.test.420202  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11])
2025-11-19 11:24:16,810 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:16,811 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, cd32ab75-cb97-49e6-8120-24749d36d630, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:16,812 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, cd32ab75-cb97-49e6-8120-24749d36d630, 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_11_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 11)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:16,814 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, cd32ab75-cb97-49e6-8120-24749d36d630, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:22,039 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:22,040 - INFO - __tests__.alter_compression.420202  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_11_compression created
2025-11-19 11:24:22,041 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:22,042 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, cf5f4ecd-7c88-4288-b2e0-8b5d66f134b5, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:22,043 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, cf5f4ecd-7c88-4288-b2e0-8b5d66f134b5, 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_11_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:22,045 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, cf5f4ecd-7c88-4288-b2e0-8b5d66f134b5, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:23,468 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:23,469 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:23,470 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 26436916-6e5c-4f6a-90f8-2baf4055bbe6, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:23,471 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 26436916-6e5c-4f6a-90f8-2baf4055bbe6, 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:23,473 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 26436916-6e5c-4f6a-90f8-2baf4055bbe6, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:26,688 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:26,689 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:26,690 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 4e082209-40d8-440d-b539-af6240060ede, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:26,691 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 4e082209-40d8-440d-b539-af6240060ede, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:26,693 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 4e082209-40d8-440d-b539-af6240060ede, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:28,096 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:28,097 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:28,098 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 2a74a18d-69a2-484e-95b8-fcd608107f7f, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:28,099 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 2a74a18d-69a2-484e-95b8-fcd608107f7f, 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:28,101 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 2a74a18d-69a2-484e-95b8-fcd608107f7f, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:30,586 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:30,587 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 375776, 'BlobCount': 128}}
2025-11-19 11:24:30,588 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:30,589 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 83b4e166-afbd-4387-b74e-391f6904a9b6, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:30,590 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 83b4e166-afbd-4387-b74e-391f6904a9b6, 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:30,591 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 83b4e166-afbd-4387-b74e-391f6904a9b6, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:34,445 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:34,446 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:34,447 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 91b8304a-19db-417c-8ca7-9240ca45a56b, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:34,448 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 91b8304a-19db-417c-8ca7-9240ca45a56b, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:34,450 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 91b8304a-19db-417c-8ca7-9240ca45a56b, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:34,945 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:34,946 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:34,947 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, e405df40-2c16-48b6-a1b3-c623bae0409e, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:34,950 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, e405df40-2c16-48b6-a1b3-c623bae0409e, 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:34,951 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, e405df40-2c16-48b6-a1b3-c623bae0409e, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:35,524 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:35,525 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 753008, 'BlobCount': 256}}
2025-11-19 11:24:35,525 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:35,526 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, ec1bb9a0-5a0f-4949-ac11-21a6e072883a, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:35,527 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, ec1bb9a0-5a0f-4949-ac11-21a6e072883a, 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:35,532 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, ec1bb9a0-5a0f-4949-ac11-21a6e072883a, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:38,165 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:38,166 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:38,167 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 7e8a3656-2248-4dec-9c13-638a166c3fa9, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:38,168 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 7e8a3656-2248-4dec-9c13-638a166c3fa9, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:38,170 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 7e8a3656-2248-4dec-9c13-638a166c3fa9, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:38,564 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:38,565 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:38,566 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, cc811a26-8188-4d6d-998d-8cc0d71b2e5c, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:38,567 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, cc811a26-8188-4d6d-998d-8cc0d71b2e5c, 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:38,569 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, cc811a26-8188-4d6d-998d-8cc0d71b2e5c, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:39,332 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:39,333 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 1129888, 'BlobCount': 384}}
2025-11-19 11:24:39,334 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:39,335 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, a8059cf3-3e3c-4e02-9924-56bab795ca7b, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:39,348 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, a8059cf3-3e3c-4e02-9924-56bab795ca7b, 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:39,350 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, a8059cf3-3e3c-4e02-9924-56bab795ca7b, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:42,576 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:42,577 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:42,578 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 0e59e321-9447-4f54-a2bd-11a13e026f64, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:42,579 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 0e59e321-9447-4f54-a2bd-11a13e026f64, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:42,581 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 0e59e321-9447-4f54-a2bd-11a13e026f64, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:42,996 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:42,997 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:42,998 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 7f847df8-4b43-40e5-9aeb-0ca8db867f9f, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:42,999 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 7f847df8-4b43-40e5-9aeb-0ca8db867f9f, 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:43,001 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 7f847df8-4b43-40e5-9aeb-0ca8db867f9f, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:43,530 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:43,531 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1507072, 'BlobCount': 512}}
2025-11-19 11:24:43,532 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:43,533 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, e2013ecf-0657-4460-a50b-05e2b8cb0076, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:43,534 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, e2013ecf-0657-4460-a50b-05e2b8cb0076, 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:43,541 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, e2013ecf-0657-4460-a50b-05e2b8cb0076, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:46,454 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:46,455 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:46,456 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, b95a3f61-3365-4149-a1bd-362fc626aea0, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:46,457 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, b95a3f61-3365-4149-a1bd-362fc626aea0, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:46,460 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, b95a3f61-3365-4149-a1bd-362fc626aea0, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:47,297 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:47,298 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:47,299 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 6b055c4b-f476-4673-8c16-e48775c3bf7d, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:47,311 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 6b055c4b-f476-4673-8c16-e48775c3bf7d, 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:47,313 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 6b055c4b-f476-4673-8c16-e48775c3bf7d, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:48,277 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:48,278 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1883936, 'BlobCount': 640}}
2025-11-19 11:24:48,279 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:48,280 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 3a0d60cd-e86a-43de-8c13-e1a5a4dd0440, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:48,282 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 3a0d60cd-e86a-43de-8c13-e1a5a4dd0440, 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:48,283 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 3a0d60cd-e86a-43de-8c13-e1a5a4dd0440, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:51,033 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:51,034 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:51,035 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 52fe3de5-7dc2-4988-a036-0d706b7214b2, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:51,036 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 52fe3de5-7dc2-4988-a036-0d706b7214b2, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:51,047 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 52fe3de5-7dc2-4988-a036-0d706b7214b2, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:51,779 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:51,780 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:51,780 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, f0342da8-05ac-4c56-a4f2-c89e697ac0c8, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:51,781 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, f0342da8-05ac-4c56-a4f2-c89e697ac0c8, 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:51,783 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, f0342da8-05ac-4c56-a4f2-c89e697ac0c8, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:52,660 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:52,661 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 2261136, 'BlobCount': 768}}
2025-11-19 11:24:52,662 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:52,663 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, bf38c1fb-826b-4058-a5b5-512527e6869d, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:52,664 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, bf38c1fb-826b-4058-a5b5-512527e6869d, 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:52,667 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, bf38c1fb-826b-4058-a5b5-512527e6869d, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:56,145 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:56,146 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:56,146 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 07a1e839-db2e-421c-bb7b-a98fe611cfbc, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:56,147 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 07a1e839-db2e-421c-bb7b-a98fe611cfbc, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:56,163 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 07a1e839-db2e-421c-bb7b-a98fe611cfbc, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:56,870 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:56,871 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:56,872 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, d1f25aac-513e-41f3-8603-0387721e5d69, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:56,873 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, d1f25aac-513e-41f3-8603-0387721e5d69, 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:56,877 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, d1f25aac-513e-41f3-8603-0387721e5d69, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:57,969 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:57,970 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2638112, 'BlobCount': 896}}
2025-11-19 11:24:57,971 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:24:57,971 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 456dc252-9fee-4248-baad-33ace3964f4b, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:24:57,973 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 456dc252-9fee-4248-baad-33ace3964f4b, 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:57,975 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 456dc252-9fee-4248-baad-33ace3964f4b, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:01,276 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:01,277 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:01,278 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, e57c3f86-c011-47ea-85d5-a5eb323fb8de, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:01,279 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, e57c3f86-c011-47ea-85d5-a5eb323fb8de, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:01,281 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, e57c3f86-c011-47ea-85d5-a5eb323fb8de, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:01,825 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:01,826 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:01,827 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, b2000057-50f7-4cc6-bf65-30a24a3e6cf4, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:01,832 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, b2000057-50f7-4cc6-bf65-30a24a3e6cf4, 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:01,834 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, b2000057-50f7-4cc6-bf65-30a24a3e6cf4, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:03,499 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:03,499 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 3015280, 'BlobCount': 1024}}
2025-11-19 11:25:03,500 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:03,501 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 2ca4ba3f-f652-4569-9fe0-2777af0d6d02, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:03,502 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 2ca4ba3f-f652-4569-9fe0-2777af0d6d02, 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:03,514 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 2ca4ba3f-f652-4569-9fe0-2777af0d6d02, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:06,596 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:06,597 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:06,598 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 15fd9efd-4170-4b72-9621-2173b19a64b4, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:06,599 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 15fd9efd-4170-4b72-9621-2173b19a64b4, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:06,601 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 15fd9efd-4170-4b72-9621-2173b19a64b4, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:07,264 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:07,266 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:07,267 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 3f902848-7e1d-4611-ba92-16c18b18121e, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:07,268 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 3f902848-7e1d-4611-ba92-16c18b18121e, 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:07,270 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 3f902848-7e1d-4611-ba92-16c18b18121e, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:08,366 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:08,366 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 3392288, 'BlobCount': 1152}}
2025-11-19 11:25:08,367 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:08,368 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 72df20ae-8840-4d8f-aa78-bcb40e883352, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:08,369 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 72df20ae-8840-4d8f-aa78-bcb40e883352, 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:08,372 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 72df20ae-8840-4d8f-aa78-bcb40e883352, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:12,115 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:12,116 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:12,117 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 45fa74be-a1ba-4bc7-8b74-43e0638e7918, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:12,118 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 45fa74be-a1ba-4bc7-8b74-43e0638e7918, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:12,120 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 45fa74be-a1ba-4bc7-8b74-43e0638e7918, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:12,664 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:12,665 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:12,666 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 30dec281-ab59-493e-b355-776a75d6f1e4, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:12,667 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 30dec281-ab59-493e-b355-776a75d6f1e4, 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:12,677 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 30dec281-ab59-493e-b355-776a75d6f1e4, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:14,296 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:14,297 - INFO - __tests__.alter_compression.420202  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3769600, 'BlobCount': 1280}}
2025-11-19 11:25:14,298 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:14,298 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 79ed5967-e56a-4188-b537-aa9f9a48a5d0, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:14,299 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 79ed5967-e56a-4188-b537-aa9f9a48a5d0, 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_11_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:14,335 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 79ed5967-e56a-4188-b537-aa9f9a48a5d0, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:16,849 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:16,850 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:16,851 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 9f47e742-7b95-4fbe-a032-39d52ea7a4ea, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:16,852 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 9f47e742-7b95-4fbe-a032-39d52ea7a4ea, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:16,855 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 9f47e742-7b95-4fbe-a032-39d52ea7a4ea, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:17,593 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:17,593 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:17,594 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 04ae1f03-41da-4852-a616-92eabdf34911, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:17,595 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 04ae1f03-41da-4852-a616-92eabdf34911, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:17,597 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 04ae1f03-41da-4852-a616-92eabdf34911, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:18,162 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:18,163 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:18,163 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 81f13189-5a4f-48df-8e23-697679d8b549, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:18,164 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 81f13189-5a4f-48df-8e23-697679d8b549, 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_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:18,166 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 81f13189-5a4f-48df-8e23-697679d8b549, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:25,775 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:31,577 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.420202  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:25:31,577 - 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:25:31,612 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.420202  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:25:31,613 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ListEndpoints, b822b60c-2e90-426c-8931-9977ad618079, localhost:8816): creating call state
2025-11-19 11:25:31,613 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ListEndpoints, b822b60c-2e90-426c-8931-9977ad618079, localhost:8816): request = { database: "/Root" }
2025-11-19 11:25:31,622 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ListEndpoints, b822b60c-2e90-426c-8931-9977ad618079, 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:25:31,623 - 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:25:31,636 - DEBUG - ydb.connection.420202  - close: Closing channel for endpoint localhost:8816
2025-11-19 11:25:35,872 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:35,873 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 3ceab0c3-0096-4657-9b71-39e4d85960cb, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:35,874 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 3ceab0c3-0096-4657-9b71-39e4d85960cb, 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_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:35,876 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 3ceab0c3-0096-4657-9b71-39e4d85960cb, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:39,676 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:49,705 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:25:49,707 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 45b919b8-bf6f-4150-876e-9725547ec2b6, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:25:49,707 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 45b919b8-bf6f-4150-876e-9725547ec2b6, 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_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:49,709 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 45b919b8-bf6f-4150-876e-9725547ec2b6, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:51,130 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:26:01,137 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:26:01,138 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 87fab66c-f2f8-4013-b0f6-c99dbeb39aba, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:26:01,139 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 87fab66c-f2f8-4013-b0f6-c99dbeb39aba, 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_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:01,141 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 87fab66c-f2f8-4013-b0f6-c99dbeb39aba, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:02,063 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:26:02,068 - INFO - root.420202  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1465344)
2025-11-19 11:26:02,068 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:26:02,069 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 03b73170-dc95-4c19-90cc-f2d5b1138d1b, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:26:02,070 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 03b73170-dc95-4c19-90cc-f2d5b1138d1b, 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:02,072 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 03b73170-dc95-4c19-90cc-f2d5b1138d1b, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:02,517 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:26:02,518 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:26:02,518 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, ed0511ac-b13f-413d-ae42-bdd94e9755c9, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:26:02,519 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, ed0511ac-b13f-413d-ae42-bdd94e9755c9, 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_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:02,523 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, ed0511ac-b13f-413d-ae42-bdd94e9755c9, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:03,574 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:26:13,580 - DEBUG - ydb.query.pool.420202  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:26:13,581 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(ExecuteQuery, 841dd787-0f5d-4f9e-9368-6fa1e1966633, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:26:13,582 - DEBUG - ydb.connection.420202  - _log_request: RpcState(ExecuteQuery, 841dd787-0f5d-4f9e-9368-6fa1e1966633, 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_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:13,583 - DEBUG - ydb.connection.420202  - _log_response: RpcState(ExecuteQuery, 841dd787-0f5d-4f9e-9368-6fa1e1966633, ghrun-alsqrntpxi.auto.internal:8816): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:14,597 - DEBUG - ydb.query.pool.420202  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==
2025-11-19 11:26:14,602 - INFO - root.420202  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1465344)
2025-11-19 11:26:14,602 - INFO - root.420202  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_11_compression` 8092160 / 1465344: 5.522361984626135
2025-11-19 11:26:14,605 - INFO - ya.test.420202  - pytest_runtest_teardown: Test teardown
2025-11-19 11:26:14,606 - DEBUG - ydb.connection.420202  - _prepare_call: RpcState(DeleteSession, d980a5db-c163-4aa7-a592-25e4ed894327, ghrun-alsqrntpxi.auto.internal:8816): creating call state
2025-11-19 11:26:14,607 - DEBUG - ydb.connection.420202  - _log_request: RpcState(DeleteSession, d980a5db-c163-4aa7-a592-25e4ed894327, ghrun-alsqrntpxi.auto.internal:8816): request = { session_id: "ydb://session/3?node_id=1&id=ZjZjYjgyOS04ZmI0ZThiNy0yM2Q4MzU5YS05ZjgyOGY1MQ==" }
2025-11-19 11:26:14,611 - DEBUG - ydb.connection.420202  - _log_response: RpcState(DeleteSession, d980a5db-c163-4aa7-a592-25e4ed894327, ghrun-alsqrntpxi.auto.internal:8816): response = { status: SUCCESS }
2025-11-19 11:26:14,613 - DEBUG - ydb.query.pool.420202  - stop: All session were deleted.
2025-11-19 11:26:14,614 - DEBUG - ydb.topic.420202  - close: Close topic client
2025-11-19 11:26:14,615 - DEBUG - ydb.topic.420202  - close: Topic client was closed
2025-11-19 11:26:14,615 - DEBUG - ydb.connection.420202  - close: Closing channel for endpoint localhost:8816
2025-11-19 11:26:14,617 - DEBUG - ydb.connection.420202  - close: Closing channel for endpoint ghrun-alsqrntpxi.auto.internal:8816
2025-11-19 11:26:14,622 - INFO - ydb.pool.Discovery.420202  - run: Successfully terminated discovery process
2025-11-19 11:26:14,623 - DEBUG - ydb.connection.420202  - _rpc_error_handler: RpcState(AttachSession, 9c921450-123f-4db4-be0b-0e26fe9b8f02, ghrun-alsqrntpxi.auto.internal:8816): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-19 11:26:36,641 - INFO - ydb.tests.library.harness.kikimr_runner.420202  - stop: Stopped node localhost:8816/1
2025-11-19 11:26:36,644 - DEBUG - library.python.filelock.420202  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10534
2025-11-19 11:26:36,645 - DEBUG - library.python.filelock.420202  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27997
2025-11-19 11:26:36,645 - DEBUG - library.python.filelock.420202  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14688
2025-11-19 11:26:36,645 - DEBUG - library.python.filelock.420202  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8816
2025-11-19 11:26:36,646 - DEBUG - library.python.filelock.420202  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61027
