2025-11-21 12:12:24,664 - INFO - ya.test.1727003  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:12:24,664 - INFO - ya.test.1727003  - pytest_runtest_setup: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19]
2025-11-21 12:12:24,665 - INFO - ya.test.1727003  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:12:24,665 - INFO - ya.test.1727003  - pytest_runtest_setup: Test setup
2025-11-21 12:12:24,668 - INFO - ya.test.1727003  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19])
2025-11-21 12:12:24,669 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:24,669 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, bfc55cfe-8b38-4914-af0c-2458005a282f, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:24,670 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, bfc55cfe-8b38-4914-af0c-2458005a282f, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_19_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 19)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:24,672 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, bfc55cfe-8b38-4914-af0c-2458005a282f, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:29,842 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:29,843 - INFO - __tests__.alter_compression.1727003  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_19_compression created
2025-11-21 12:12:29,844 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:29,844 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, d54ab21b-8929-40f9-b3c0-fa37b97c1f63, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:29,845 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, d54ab21b-8929-40f9-b3c0-fa37b97c1f63, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:29,846 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, d54ab21b-8929-40f9-b3c0-fa37b97c1f63, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:31,548 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:31,549 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:31,549 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 17ae8125-77fd-48cc-8d3d-d9dbab421074, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:31,550 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 17ae8125-77fd-48cc-8d3d-d9dbab421074, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:31,552 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 17ae8125-77fd-48cc-8d3d-d9dbab421074, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:37,495 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:37,496 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:37,497 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 1e8cd38d-1393-4902-9535-8dddcb468391, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:37,498 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 1e8cd38d-1393-4902-9535-8dddcb468391, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:37,504 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 1e8cd38d-1393-4902-9535-8dddcb468391, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:38,556 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:38,557 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:38,558 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 138f711f-928c-481e-997c-91eae8ffd6fa, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:38,559 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 138f711f-928c-481e-997c-91eae8ffd6fa, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:38,560 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 138f711f-928c-481e-997c-91eae8ffd6fa, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:41,187 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:41,188 - INFO - __tests__.alter_compression.1727003  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-21 12:12:41,188 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:41,189 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, db477414-6343-4633-9b60-3fc843e9db10, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:41,190 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, db477414-6343-4633-9b60-3fc843e9db10, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:41,195 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, db477414-6343-4633-9b60-3fc843e9db10, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:46,430 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:46,431 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:46,431 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, e2c08160-843a-4af5-bb1b-8401e16d7806, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:46,432 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, e2c08160-843a-4af5-bb1b-8401e16d7806, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:46,433 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, e2c08160-843a-4af5-bb1b-8401e16d7806, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:46,833 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:46,834 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:46,835 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 91cdbb17-2957-41a3-94c3-0e60ec7b4357, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:46,836 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 91cdbb17-2957-41a3-94c3-0e60ec7b4357, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:46,837 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 91cdbb17-2957-41a3-94c3-0e60ec7b4357, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:47,321 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:47,321 - INFO - __tests__.alter_compression.1727003  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-21 12:12:47,322 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:47,322 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 2445dafa-2927-4dc7-af2d-6aeaf2154168, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:47,325 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 2445dafa-2927-4dc7-af2d-6aeaf2154168, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:47,326 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 2445dafa-2927-4dc7-af2d-6aeaf2154168, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:52,088 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:52,089 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:52,090 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, e75727d2-56b5-422d-8ec3-7772e7d02df6, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:52,091 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, e75727d2-56b5-422d-8ec3-7772e7d02df6, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:52,096 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, e75727d2-56b5-422d-8ec3-7772e7d02df6, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:52,549 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:52,550 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:52,551 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 7d5ff63f-7c14-4051-a8ed-0906a03f25c5, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:52,553 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 7d5ff63f-7c14-4051-a8ed-0906a03f25c5, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:52,555 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 7d5ff63f-7c14-4051-a8ed-0906a03f25c5, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:53,315 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:53,316 - INFO - __tests__.alter_compression.1727003  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-21 12:12:53,316 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:53,317 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 6659b323-fae3-4582-bb34-73c8626711b8, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:53,318 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 6659b323-fae3-4582-bb34-73c8626711b8, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:53,320 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 6659b323-fae3-4582-bb34-73c8626711b8, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:57,985 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:57,986 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:57,986 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 1cd3e164-2ee7-4b86-8d15-a388f98f9d20, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:57,987 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 1cd3e164-2ee7-4b86-8d15-a388f98f9d20, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:58,000 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 1cd3e164-2ee7-4b86-8d15-a388f98f9d20, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:58,365 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:58,366 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:58,367 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 1b474cc1-e412-42b0-8c94-a27c2ce7ae61, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:58,368 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 1b474cc1-e412-42b0-8c94-a27c2ce7ae61, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:58,369 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 1b474cc1-e412-42b0-8c94-a27c2ce7ae61, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:58,962 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:58,963 - INFO - __tests__.alter_compression.1727003  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-21 12:12:58,964 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:12:58,964 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 5dc86003-0429-4989-8cad-341a0aa7e4a8, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:12:58,965 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 5dc86003-0429-4989-8cad-341a0aa7e4a8, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:58,967 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 5dc86003-0429-4989-8cad-341a0aa7e4a8, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:04,676 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:04,677 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:04,678 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, b29b560a-739c-4fa4-adbe-694e77dfab58, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:04,678 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, b29b560a-739c-4fa4-adbe-694e77dfab58, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:04,680 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, b29b560a-739c-4fa4-adbe-694e77dfab58, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:05,039 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:05,039 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:05,040 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 4a65a0b5-b05d-4d5b-8944-92cdfed5c52f, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:05,041 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 4a65a0b5-b05d-4d5b-8944-92cdfed5c52f, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:05,042 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 4a65a0b5-b05d-4d5b-8944-92cdfed5c52f, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:05,583 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:05,584 - INFO - __tests__.alter_compression.1727003  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-21 12:13:05,584 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:05,585 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 3d775587-69f2-49f9-8b1e-a0c9338976e4, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:05,586 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 3d775587-69f2-49f9-8b1e-a0c9338976e4, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:05,587 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 3d775587-69f2-49f9-8b1e-a0c9338976e4, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:09,328 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1727003  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 12:13:09,328 - DEBUG - ydb.connection.1727003  - 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:09,355 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1727003  - context_resolve: Resolving endpoints for database /Root
2025-11-21 12:13:09,356 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ListEndpoints, e47f164a-eca2-4c44-aecd-1b524c11f5b2, localhost:13770): creating call state
2025-11-21 12:13:09,357 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ListEndpoints, e47f164a-eca2-4c44-aecd-1b524c11f5b2, localhost:13770): request = { database: "/Root" }
2025-11-21 12:13:09,374 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ListEndpoints, e47f164a-eca2-4c44-aecd-1b524c11f5b2, localhost:13770): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ts5cnd6jre.auto.internal" port: 13770 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:09,375 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1727003  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ts5cnd6jre.auto.internal:13770, location 1, ssl: False>]>
2025-11-21 12:13:09,376 - DEBUG - ydb.connection.1727003  - close: Closing channel for endpoint localhost:13770
2025-11-21 12:13:10,716 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:10,717 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:10,718 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 41908e2e-237f-4946-9e9a-5dad957656bf, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:10,719 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 41908e2e-237f-4946-9e9a-5dad957656bf, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:10,725 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 41908e2e-237f-4946-9e9a-5dad957656bf, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:11,129 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:11,130 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:11,131 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, dc971542-1101-4bd8-aaec-2b20cfa8cb92, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:11,132 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, dc971542-1101-4bd8-aaec-2b20cfa8cb92, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:11,140 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, dc971542-1101-4bd8-aaec-2b20cfa8cb92, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:11,812 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:11,813 - INFO - __tests__.alter_compression.1727003  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-21 12:13:11,814 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:11,814 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 01806617-a348-4a3e-a7e5-35dec909081b, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:11,820 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 01806617-a348-4a3e-a7e5-35dec909081b, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:11,822 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 01806617-a348-4a3e-a7e5-35dec909081b, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:16,739 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:16,740 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:16,741 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 64116534-457b-4056-985f-0e5fb080078e, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:16,742 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 64116534-457b-4056-985f-0e5fb080078e, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:16,744 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 64116534-457b-4056-985f-0e5fb080078e, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:17,216 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:17,216 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:17,217 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 6dffeb7a-ba1d-4a83-b6c5-050232eb676d, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:17,219 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 6dffeb7a-ba1d-4a83-b6c5-050232eb676d, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:17,222 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 6dffeb7a-ba1d-4a83-b6c5-050232eb676d, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:18,209 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:18,210 - INFO - __tests__.alter_compression.1727003  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-21 12:13:18,210 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:18,211 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 5c22a4c0-d2dc-45fa-a4ed-4d4a100799a9, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:18,213 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 5c22a4c0-d2dc-45fa-a4ed-4d4a100799a9, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:18,214 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 5c22a4c0-d2dc-45fa-a4ed-4d4a100799a9, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:23,271 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:23,272 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:23,273 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 96823764-f4f3-46e1-b6b5-4cdba69dc7d8, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:23,274 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 96823764-f4f3-46e1-b6b5-4cdba69dc7d8, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:23,281 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 96823764-f4f3-46e1-b6b5-4cdba69dc7d8, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:23,608 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:23,609 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:23,610 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, e1e416b9-d45b-4d32-8d18-e5ef44e6981a, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:23,611 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, e1e416b9-d45b-4d32-8d18-e5ef44e6981a, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:23,616 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, e1e416b9-d45b-4d32-8d18-e5ef44e6981a, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:24,378 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:24,379 - INFO - __tests__.alter_compression.1727003  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-21 12:13:24,379 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:24,380 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 6ef08c1c-2db2-4b0c-bc37-b6565b7d42ed, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:24,381 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 6ef08c1c-2db2-4b0c-bc37-b6565b7d42ed, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:24,383 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 6ef08c1c-2db2-4b0c-bc37-b6565b7d42ed, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:29,763 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:29,763 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:29,764 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, e565857f-2dfd-4810-b602-c6bd329c00a0, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:29,765 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, e565857f-2dfd-4810-b602-c6bd329c00a0, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:29,767 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, e565857f-2dfd-4810-b602-c6bd329c00a0, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:30,143 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:30,144 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:30,145 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 69ee0512-f3ae-4276-9355-d5ce04dcffda, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:30,146 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 69ee0512-f3ae-4276-9355-d5ce04dcffda, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:30,152 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 69ee0512-f3ae-4276-9355-d5ce04dcffda, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:31,004 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:31,005 - INFO - __tests__.alter_compression.1727003  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-21 12:13:31,005 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:31,006 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, ea77aaae-40e3-4a0a-9c28-46b01daa9dd4, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:31,007 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, ea77aaae-40e3-4a0a-9c28-46b01daa9dd4, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:31,009 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, ea77aaae-40e3-4a0a-9c28-46b01daa9dd4, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:35,736 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:35,737 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:35,738 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 0bfeb18e-7094-4e6e-b962-183311f2314c, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:35,738 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 0bfeb18e-7094-4e6e-b962-183311f2314c, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:35,740 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 0bfeb18e-7094-4e6e-b962-183311f2314c, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:36,039 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:36,040 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:36,041 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, ac42e99d-f41f-4bd1-976f-00d89e755304, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:36,042 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, ac42e99d-f41f-4bd1-976f-00d89e755304, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:36,044 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, ac42e99d-f41f-4bd1-976f-00d89e755304, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:36,806 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:36,807 - INFO - __tests__.alter_compression.1727003  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-21 12:13:36,807 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:36,808 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 409b013a-f4c6-40ef-a7cc-16c9f83c7a28, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:36,809 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 409b013a-f4c6-40ef-a7cc-16c9f83c7a28, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:36,810 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 409b013a-f4c6-40ef-a7cc-16c9f83c7a28, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:38,018 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:38,019 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:38,019 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 31efba8b-01db-459f-8252-058cd8f47007, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:38,020 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 31efba8b-01db-459f-8252-058cd8f47007, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:38,022 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 31efba8b-01db-459f-8252-058cd8f47007, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:38,284 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:38,285 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:38,286 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, cc46602d-1120-4573-b9cb-f0f0b5f612f7, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:38,287 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, cc46602d-1120-4573-b9cb-f0f0b5f612f7, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:38,288 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, cc46602d-1120-4573-b9cb-f0f0b5f612f7, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:38,549 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:38,550 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:38,550 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 2421b349-cd59-48af-9f1d-ad38ea6f6038, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:38,551 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 2421b349-cd59-48af-9f1d-ad38ea6f6038, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:38,553 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 2421b349-cd59-48af-9f1d-ad38ea6f6038, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:42,530 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:52,573 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:52,574 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 11158e76-c4ae-4f43-915c-3680b7a8673d, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:52,575 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 11158e76-c4ae-4f43-915c-3680b7a8673d, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:52,576 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 11158e76-c4ae-4f43-915c-3680b7a8673d, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:55,936 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:56,012 - INFO - root.1727003  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1595704)
2025-11-21 12:13:56,013 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:56,014 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, c059c3f8-c536-4cba-b12d-0700d1d701bf, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:56,015 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, c059c3f8-c536-4cba-b12d-0700d1d701bf, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:56,016 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, c059c3f8-c536-4cba-b12d-0700d1d701bf, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:56,348 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:56,348 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:13:56,349 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 9552be56-5314-40c2-99c9-c5da4d968ec0, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:13:56,350 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 9552be56-5314-40c2-99c9-c5da4d968ec0, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:56,353 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 9552be56-5314-40c2-99c9-c5da4d968ec0, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:00,122 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:14:10,175 - DEBUG - ydb.query.pool.1727003  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:14:10,176 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(ExecuteQuery, 6bb073f7-58f3-413a-89c0-fb7eed2f0588, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:14:10,177 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(ExecuteQuery, 6bb073f7-58f3-413a-89c0-fb7eed2f0588, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:10,180 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(ExecuteQuery, 6bb073f7-58f3-413a-89c0-fb7eed2f0588, ghrun-ts5cnd6jre.auto.internal:13770): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:13,787 - DEBUG - ydb.query.pool.1727003  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==
2025-11-21 12:14:13,836 - INFO - root.1727003  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1595704)
2025-11-21 12:14:13,836 - INFO - root.1727003  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_19_compression` 8092160 / 1595704: 5.071216215538722
2025-11-21 12:14:13,839 - INFO - ya.test.1727003  - pytest_runtest_teardown: Test teardown
2025-11-21 12:14:13,840 - DEBUG - ydb.connection.1727003  - _prepare_call: RpcState(DeleteSession, 71123045-faa4-4894-98f8-8b3c825f09b9, ghrun-ts5cnd6jre.auto.internal:13770): creating call state
2025-11-21 12:14:13,841 - DEBUG - ydb.connection.1727003  - _log_request: RpcState(DeleteSession, 71123045-faa4-4894-98f8-8b3c825f09b9, ghrun-ts5cnd6jre.auto.internal:13770): request = { session_id: "ydb://session/3?node_id=1&id=MmUyMThmMy02NTZiZDkyYS02NTgzNjMwZC1iOTEzMzQ2ZQ==" }
2025-11-21 12:14:13,852 - DEBUG - ydb.connection.1727003  - _log_response: RpcState(DeleteSession, 71123045-faa4-4894-98f8-8b3c825f09b9, ghrun-ts5cnd6jre.auto.internal:13770): response = { status: SUCCESS }
2025-11-21 12:14:13,854 - DEBUG - ydb.query.pool.1727003  - stop: All session were deleted.
2025-11-21 12:14:13,857 - DEBUG - ydb.topic.1727003  - close: Close topic client
2025-11-21 12:14:13,857 - DEBUG - ydb.topic.1727003  - close: Topic client was closed
2025-11-21 12:14:13,855 - DEBUG - ydb.connection.1727003  - _rpc_error_handler: RpcState(AttachSession, b923cd81-3e32-4051-9ec5-a089f94b9e5a, ghrun-ts5cnd6jre.auto.internal:13770): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-21 12:14:13,858 - DEBUG - ydb.connection.1727003  - close: Closing channel for endpoint localhost:13770
2025-11-21 12:14:13,859 - DEBUG - ydb.connection.1727003  - close: Closing channel for endpoint ghrun-ts5cnd6jre.auto.internal:13770
2025-11-21 12:14:13,861 - INFO - ydb.pool.Discovery.1727003  - run: Successfully terminated discovery process
2025-11-21 12:14:30,872 - INFO - ydb.tests.library.harness.kikimr_runner.1727003  - stop: Stopped node localhost:13770/1
2025-11-21 12:14:30,873 - DEBUG - library.python.filelock.1727003  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26903
2025-11-21 12:14:30,875 - DEBUG - library.python.filelock.1727003  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19233
2025-11-21 12:14:30,875 - DEBUG - library.python.filelock.1727003  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1705
2025-11-21 12:14:30,876 - DEBUG - library.python.filelock.1727003  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13770
2025-11-21 12:14:30,877 - DEBUG - library.python.filelock.1727003  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28665
