2025-11-21 12:11:43,110 - INFO - ya.test.1724271  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:11:43,111 - INFO - ya.test.1724271  - pytest_runtest_setup: test_all_supported_compression[zstd_5_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 5]
2025-11-21 12:11:43,111 - INFO - ya.test.1724271  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:11:43,111 - INFO - ya.test.1724271  - pytest_runtest_setup: Test setup
2025-11-21 12:11:43,113 - INFO - ya.test.1724271  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_5_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 5])
2025-11-21 12:11:43,114 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:43,115 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 921e3237-99b7-4328-ab48-2b707bbe599b, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:43,117 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 921e3237-99b7-4328-ab48-2b707bbe599b, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_5_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 5)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:43,118 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 921e3237-99b7-4328-ab48-2b707bbe599b, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:48,709 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:48,710 - INFO - __tests__.alter_compression.1724271  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_5_compression created
2025-11-21 12:11:48,710 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:48,711 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 9c84e9b0-173d-45ae-a574-ed412d4dd342, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:48,712 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 9c84e9b0-173d-45ae-a574-ed412d4dd342, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_5_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:48,727 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 9c84e9b0-173d-45ae-a574-ed412d4dd342, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:49,947 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:49,948 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:49,948 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, fe0bc029-2dd4-403e-abaa-020a8197a9ad, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:49,949 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, fe0bc029-2dd4-403e-abaa-020a8197a9ad, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:49,958 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, fe0bc029-2dd4-403e-abaa-020a8197a9ad, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:51,870 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:51,870 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:51,871 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 2886a225-1fcf-4c38-bb9f-d69059301b3c, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:51,872 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 2886a225-1fcf-4c38-bb9f-d69059301b3c, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:51,873 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 2886a225-1fcf-4c38-bb9f-d69059301b3c, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:53,621 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:53,622 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:53,623 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, c27eb7bb-f116-420a-a81e-a092cbaf9664, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:53,624 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, c27eb7bb-f116-420a-a81e-a092cbaf9664, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:53,625 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, c27eb7bb-f116-420a-a81e-a092cbaf9664, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:56,564 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:56,565 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318064, 'BlobCount': 128}}
2025-11-21 12:11:56,566 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:56,566 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 0e48516e-c230-41e6-9cd5-3ca13fa377b9, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:56,567 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 0e48516e-c230-41e6-9cd5-3ca13fa377b9, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:56,569 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 0e48516e-c230-41e6-9cd5-3ca13fa377b9, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:58,266 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:58,266 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:58,267 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 8d70e7e1-8892-4c20-a59f-88a2b675c2d1, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:58,268 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 8d70e7e1-8892-4c20-a59f-88a2b675c2d1, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:58,276 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 8d70e7e1-8892-4c20-a59f-88a2b675c2d1, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:58,703 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:58,705 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:58,706 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 703cafcc-2412-4fee-9445-5952597cb291, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:58,707 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 703cafcc-2412-4fee-9445-5952597cb291, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:58,709 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 703cafcc-2412-4fee-9445-5952597cb291, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:59,321 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:59,321 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637360, 'BlobCount': 256}}
2025-11-21 12:11:59,322 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:59,323 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 7d9e123f-354b-4531-8d01-6f64ff5404e9, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:59,335 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 7d9e123f-354b-4531-8d01-6f64ff5404e9, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:59,337 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 7d9e123f-354b-4531-8d01-6f64ff5404e9, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:01,403 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:01,404 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:01,405 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 376fc806-92e5-4454-9d71-c9bf8235839b, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:01,406 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 376fc806-92e5-4454-9d71-c9bf8235839b, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:01,408 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 376fc806-92e5-4454-9d71-c9bf8235839b, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:01,884 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:01,885 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:01,885 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 548ba6c1-8071-4280-b3a2-4f682be05397, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:01,886 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 548ba6c1-8071-4280-b3a2-4f682be05397, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_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:01,895 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 548ba6c1-8071-4280-b3a2-4f682be05397, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:03,056 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:03,057 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956400, 'BlobCount': 384}}
2025-11-21 12:12:03,057 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:03,058 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, cfe6756e-eb39-4f8c-bdd1-0d97623610d9, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:03,061 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, cfe6756e-eb39-4f8c-bdd1-0d97623610d9, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:03,063 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, cfe6756e-eb39-4f8c-bdd1-0d97623610d9, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:05,085 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:05,086 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:05,087 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 320d1b25-cb0e-40a2-bd66-e3cbd52ad23e, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:05,088 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 320d1b25-cb0e-40a2-bd66-e3cbd52ad23e, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:05,094 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 320d1b25-cb0e-40a2-bd66-e3cbd52ad23e, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:06,012 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:06,013 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:06,014 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, cfa9e808-d8c8-48db-afa1-6f8ae44213e6, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:06,014 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, cfa9e808-d8c8-48db-afa1-6f8ae44213e6, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_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:06,022 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, cfa9e808-d8c8-48db-afa1-6f8ae44213e6, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:06,712 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:06,712 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275760, 'BlobCount': 512}}
2025-11-21 12:12:06,713 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:06,714 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, e2ace9eb-6d7c-4b04-92d3-7137c7c3552a, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:06,715 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, e2ace9eb-6d7c-4b04-92d3-7137c7c3552a, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:06,716 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, e2ace9eb-6d7c-4b04-92d3-7137c7c3552a, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:08,693 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:08,694 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:08,695 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 31b0d36c-b81d-4c57-ae58-23b1da75a076, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:08,695 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 31b0d36c-b81d-4c57-ae58-23b1da75a076, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:08,707 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 31b0d36c-b81d-4c57-ae58-23b1da75a076, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:09,253 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:09,254 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:09,255 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 6cfc2f88-6248-4687-b6bf-7d2c30f01169, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:09,256 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 6cfc2f88-6248-4687-b6bf-7d2c30f01169, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_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:09,257 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 6cfc2f88-6248-4687-b6bf-7d2c30f01169, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:10,085 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:10,085 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594720, 'BlobCount': 640}}
2025-11-21 12:12:10,086 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:10,087 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 141b2241-4e0e-4950-b599-112a5273296c, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:10,088 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 141b2241-4e0e-4950-b599-112a5273296c, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:10,089 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 141b2241-4e0e-4950-b599-112a5273296c, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:10,163 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1724271  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 12:12:10,164 - DEBUG - ydb.connection.1724271  - 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:12:10,209 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1724271  - context_resolve: Resolving endpoints for database /Root
2025-11-21 12:12:10,210 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ListEndpoints, f02c4203-0c40-438b-9adf-dee620d1cc77, localhost:19315): creating call state
2025-11-21 12:12:10,210 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ListEndpoints, f02c4203-0c40-438b-9adf-dee620d1cc77, localhost:19315): request = { database: "/Root" }
2025-11-21 12:12:10,225 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ListEndpoints, f02c4203-0c40-438b-9adf-dee620d1cc77, localhost:19315): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ts5cnd6jre.auto.internal" port: 19315 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:12:10,226 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1724271  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ts5cnd6jre.auto.internal:19315, location 1, ssl: False>]>
2025-11-21 12:12:10,227 - DEBUG - ydb.connection.1724271  - close: Closing channel for endpoint localhost:19315
2025-11-21 12:12:11,745 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:11,745 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:11,746 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 62be7b9d-fa38-4ed6-9534-2a35295afe6f, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:11,746 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 62be7b9d-fa38-4ed6-9534-2a35295afe6f, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:11,748 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 62be7b9d-fa38-4ed6-9534-2a35295afe6f, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:12,329 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:12,329 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:12,330 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, fc1e329d-5a53-49d6-bdcb-71d5a06a5d52, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:12,331 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, fc1e329d-5a53-49d6-bdcb-71d5a06a5d52, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_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:12,334 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, fc1e329d-5a53-49d6-bdcb-71d5a06a5d52, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:13,218 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:13,218 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913904, 'BlobCount': 768}}
2025-11-21 12:12:13,219 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:13,220 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, d3960a5c-ea05-4842-911c-19356c29c155, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:13,221 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, d3960a5c-ea05-4842-911c-19356c29c155, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:13,222 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, d3960a5c-ea05-4842-911c-19356c29c155, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:14,786 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:14,787 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:14,789 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, fd46cbb3-8fcf-47e8-9ed0-742ca5226f40, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:14,789 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, fd46cbb3-8fcf-47e8-9ed0-742ca5226f40, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:14,791 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, fd46cbb3-8fcf-47e8-9ed0-742ca5226f40, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:15,181 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:15,182 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:15,183 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 823cde77-ca55-4534-a95f-10db5fc97ce8, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:15,184 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 823cde77-ca55-4534-a95f-10db5fc97ce8, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_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:15,217 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 823cde77-ca55-4534-a95f-10db5fc97ce8, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:16,462 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:16,463 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233040, 'BlobCount': 896}}
2025-11-21 12:12:16,463 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:16,464 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 9e08a26c-0c6a-431c-b17b-53a5f6313674, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:16,465 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 9e08a26c-0c6a-431c-b17b-53a5f6313674, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:16,479 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 9e08a26c-0c6a-431c-b17b-53a5f6313674, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:18,776 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:18,776 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:18,777 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 05e8625b-28f8-48d1-bcfa-6ec4ea98d9b8, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:18,778 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 05e8625b-28f8-48d1-bcfa-6ec4ea98d9b8, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:18,784 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 05e8625b-28f8-48d1-bcfa-6ec4ea98d9b8, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:19,306 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:19,307 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:19,308 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 21d6246a-b5ae-457a-ad11-db53a0c62e67, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:19,309 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 21d6246a-b5ae-457a-ad11-db53a0c62e67, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_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:19,310 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 21d6246a-b5ae-457a-ad11-db53a0c62e67, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:20,239 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:20,240 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552528, 'BlobCount': 1024}}
2025-11-21 12:12:20,240 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:20,241 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, e4cfe013-f23c-477b-9d54-2918ede8bbde, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:20,241 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, e4cfe013-f23c-477b-9d54-2918ede8bbde, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:20,251 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, e4cfe013-f23c-477b-9d54-2918ede8bbde, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:21,999 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:22,000 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:22,001 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 5b41074c-aa90-450d-be92-a1ae4367de73, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:22,002 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 5b41074c-aa90-450d-be92-a1ae4367de73, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:22,003 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 5b41074c-aa90-450d-be92-a1ae4367de73, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:22,585 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:22,585 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:22,586 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, be6e9d40-9f96-4264-b991-0080ef0b8c04, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:22,587 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, be6e9d40-9f96-4264-b991-0080ef0b8c04, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_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:22,588 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, be6e9d40-9f96-4264-b991-0080ef0b8c04, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:23,733 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:23,734 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871536, 'BlobCount': 1152}}
2025-11-21 12:12:23,735 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:23,735 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, a62093ed-61c3-40fb-ad6d-b6d0452c66a2, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:23,736 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, a62093ed-61c3-40fb-ad6d-b6d0452c66a2, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:23,738 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, a62093ed-61c3-40fb-ad6d-b6d0452c66a2, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:25,220 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:25,221 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:25,221 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 6ac9f02a-90c5-4f2f-bab1-ba8aedcd502a, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:25,222 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 6ac9f02a-90c5-4f2f-bab1-ba8aedcd502a, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:25,224 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 6ac9f02a-90c5-4f2f-bab1-ba8aedcd502a, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:25,625 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:25,626 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:25,626 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 3e39ffc0-6a6e-47a7-a9f4-720bcf1fb57a, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:25,627 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 3e39ffc0-6a6e-47a7-a9f4-720bcf1fb57a, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_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:25,630 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 3e39ffc0-6a6e-47a7-a9f4-720bcf1fb57a, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:26,511 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:26,512 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191040, 'BlobCount': 1280}}
2025-11-21 12:12:26,512 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:26,513 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 4945dbaf-bb5e-40b7-b745-7fc23e893bc4, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:26,513 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 4945dbaf-bb5e-40b7-b745-7fc23e893bc4, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_5_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:26,518 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 4945dbaf-bb5e-40b7-b745-7fc23e893bc4, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:28,084 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:28,085 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:28,085 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 3d52936f-7cd7-48f4-a67a-0bd2c0adcc42, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:28,086 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 3d52936f-7cd7-48f4-a67a-0bd2c0adcc42, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:28,088 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 3d52936f-7cd7-48f4-a67a-0bd2c0adcc42, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:28,596 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:28,597 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:28,597 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, f482bec9-2eba-4e30-8b2c-4626960dc63e, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:28,598 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, f482bec9-2eba-4e30-8b2c-4626960dc63e, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:28,605 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, f482bec9-2eba-4e30-8b2c-4626960dc63e, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:28,981 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:28,982 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:28,982 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 487b52a6-192d-4360-88ff-e82a6e7f2760, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:28,983 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 487b52a6-192d-4360-88ff-e82a6e7f2760, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:28,984 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 487b52a6-192d-4360-88ff-e82a6e7f2760, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:35,421 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:45,514 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:45,515 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, a376a8f2-e93e-494a-9b7b-0366db46293e, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:45,516 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, a376a8f2-e93e-494a-9b7b-0366db46293e, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:45,518 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, a376a8f2-e93e-494a-9b7b-0366db46293e, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:49,371 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:49,403 - INFO - root.1724271  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1595520)
2025-11-21 12:12:49,404 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:49,404 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 92005bdc-005c-46f1-96cf-bf4d6164d322, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:49,405 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 92005bdc-005c-46f1-96cf-bf4d6164d322, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:49,407 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 92005bdc-005c-46f1-96cf-bf4d6164d322, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:49,789 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:12:50,689 - ERROR - ya.test.1724271  - logreport: ydb/tests/olap/column_family/compression/alter_compression.py:194: in test_all_supported_compression
    assert table.get_portion_stat_by_tier()['__DEFAULT']['Rows'] == expected_raw // 8
E   assert 1015690 == (8000000 // 8)
2025-11-21 12:12:50,691 - INFO - ya.test.1724271  - pytest_runtest_teardown: Test teardown
2025-11-21 12:12:50,692 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(DeleteSession, 680fd584-c11b-4a7e-b2b9-a79f85a9f63b, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:12:50,692 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(DeleteSession, 680fd584-c11b-4a7e-b2b9-a79f85a9f63b, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" }
2025-11-21 12:12:50,697 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(DeleteSession, 680fd584-c11b-4a7e-b2b9-a79f85a9f63b, ghrun-ts5cnd6jre.auto.internal:19315): response = { status: SUCCESS }
2025-11-21 12:12:50,698 - DEBUG - ydb.query.pool.1724271  - stop: All session were deleted.
2025-11-21 12:12:50,699 - DEBUG - ydb.topic.1724271  - close: Close topic client
2025-11-21 12:12:50,699 - DEBUG - ydb.topic.1724271  - close: Topic client was closed
2025-11-21 12:12:50,700 - DEBUG - ydb.connection.1724271  - close: Closing channel for endpoint localhost:19315
2025-11-21 12:12:50,706 - DEBUG - ydb.connection.1724271  - _rpc_error_handler: RpcState(AttachSession, b9627864-a88c-435b-a6cc-5727d4c53ca2, ghrun-ts5cnd6jre.auto.internal:19315): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-21 12:12:50,714 - DEBUG - ydb.connection.1724271  - close: Closing channel for endpoint ghrun-ts5cnd6jre.auto.internal:19315
2025-11-21 12:12:50,716 - INFO - ydb.pool.Discovery.1724271  - run: Successfully terminated discovery process
2025-11-21 12:13:12,731 - INFO - ydb.tests.library.harness.kikimr_runner.1724271  - stop: Stopped node localhost:19315/1
2025-11-21 12:13:12,735 - DEBUG - library.python.filelock.1724271  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25316
2025-11-21 12:13:12,740 - DEBUG - library.python.filelock.1724271  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22358
2025-11-21 12:13:12,751 - DEBUG - library.python.filelock.1724271  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13074
2025-11-21 12:13:12,752 - DEBUG - library.python.filelock.1724271  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19315
2025-11-21 12:13:12,753 - DEBUG - library.python.filelock.1724271  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17461
