2025-12-23 15:38:54,810 - INFO - ya.test.668434  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:38:54,811 - INFO - ya.test.668434  - pytest_runtest_setup: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10]
2025-12-23 15:38:54,811 - INFO - ya.test.668434  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:38:54,811 - INFO - ya.test.668434  - pytest_runtest_setup: Test setup
2025-12-23 15:38:54,813 - INFO - ya.test.668434  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10])
2025-12-23 15:38:54,814 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:38:54,815 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, e5281b06-9c12-4b74-bf79-83e917e59219, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:38:54,816 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, e5281b06-9c12-4b74-bf79-83e917e59219, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_10_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 10)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:54,817 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, e5281b06-9c12-4b74-bf79-83e917e59219, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:57,564 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:38:57,565 - INFO - __tests__.alter_compression.668434  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_10_compression created
2025-12-23 15:38:57,565 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:38:57,566 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 76fc0dff-4a53-4005-b9e9-01bac46d0535, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:38:57,567 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 76fc0dff-4a53-4005-b9e9-01bac46d0535, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_10_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:57,568 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 76fc0dff-4a53-4005-b9e9-01bac46d0535, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:58,572 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:38:58,573 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:38:58,574 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 80a1d1a8-2967-4a51-a467-9492ac22ed9f, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:38:58,575 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 80a1d1a8-2967-4a51-a467-9492ac22ed9f, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:58,576 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 80a1d1a8-2967-4a51-a467-9492ac22ed9f, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:00,237 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:00,238 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:00,239 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, e71536f7-2718-4ec7-9dcf-0e8878609236, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:00,240 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, e71536f7-2718-4ec7-9dcf-0e8878609236, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:00,241 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, e71536f7-2718-4ec7-9dcf-0e8878609236, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:01,227 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:01,228 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:01,229 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 3d041ba5-aa92-47e1-87aa-abfb98a7ba10, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:01,229 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 3d041ba5-aa92-47e1-87aa-abfb98a7ba10, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:01,231 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 3d041ba5-aa92-47e1-87aa-abfb98a7ba10, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:03,330 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:03,330 - INFO - __tests__.alter_compression.668434  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318048, 'BlobCount': 128}}
2025-12-23 15:39:03,331 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:03,331 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 40ab0d2a-3936-4761-ac5e-2cae67605d28, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:03,332 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 40ab0d2a-3936-4761-ac5e-2cae67605d28, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:03,334 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 40ab0d2a-3936-4761-ac5e-2cae67605d28, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:05,405 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:05,406 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:05,407 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, e5684f2e-473f-4b8a-b407-905b6a5cdcca, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:05,408 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, e5684f2e-473f-4b8a-b407-905b6a5cdcca, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:05,409 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, e5684f2e-473f-4b8a-b407-905b6a5cdcca, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:05,718 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:05,718 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:05,719 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 9ed0aff3-6064-49bf-8839-f9669ed19499, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:05,720 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 9ed0aff3-6064-49bf-8839-f9669ed19499, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:05,721 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 9ed0aff3-6064-49bf-8839-f9669ed19499, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:06,162 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:06,163 - INFO - __tests__.alter_compression.668434  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637344, 'BlobCount': 256}}
2025-12-23 15:39:06,163 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:06,164 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 250dc763-2b92-4e4e-ae82-52c2441d4dfb, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:06,168 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 250dc763-2b92-4e4e-ae82-52c2441d4dfb, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:06,170 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 250dc763-2b92-4e4e-ae82-52c2441d4dfb, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:08,016 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:08,017 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:08,017 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 9d2ebb6c-385d-4c8d-8719-7b091dc9b126, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:08,018 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 9d2ebb6c-385d-4c8d-8719-7b091dc9b126, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:08,019 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 9d2ebb6c-385d-4c8d-8719-7b091dc9b126, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:08,393 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:08,394 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:08,394 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 32bcfb6c-acd3-4a58-91b0-570d59019d03, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:08,395 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 32bcfb6c-acd3-4a58-91b0-570d59019d03, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:08,397 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 32bcfb6c-acd3-4a58-91b0-570d59019d03, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:08,945 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:08,946 - INFO - __tests__.alter_compression.668434  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956384, 'BlobCount': 384}}
2025-12-23 15:39:08,946 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:08,947 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 095a3508-422e-4977-8a0f-e889aa925595, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:08,948 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 095a3508-422e-4977-8a0f-e889aa925595, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:08,949 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 095a3508-422e-4977-8a0f-e889aa925595, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:10,903 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:10,904 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:10,905 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, c35d6924-ac7b-4a38-9079-1edfa03f2c93, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:10,906 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, c35d6924-ac7b-4a38-9079-1edfa03f2c93, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:10,908 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, c35d6924-ac7b-4a38-9079-1edfa03f2c93, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:11,205 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:11,206 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:11,206 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, ff257ee1-4dcd-4f69-9ad2-f4d9032eab95, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:11,207 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, ff257ee1-4dcd-4f69-9ad2-f4d9032eab95, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:11,209 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, ff257ee1-4dcd-4f69-9ad2-f4d9032eab95, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:11,745 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:11,746 - INFO - __tests__.alter_compression.668434  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275744, 'BlobCount': 512}}
2025-12-23 15:39:11,747 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:11,747 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 575965bf-c98f-48fd-a55e-06981248a6c1, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:11,749 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 575965bf-c98f-48fd-a55e-06981248a6c1, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:11,750 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 575965bf-c98f-48fd-a55e-06981248a6c1, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:13,553 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:13,554 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:13,555 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 7c2c880a-86be-4f24-872b-976f894b12ee, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:13,556 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 7c2c880a-86be-4f24-872b-976f894b12ee, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:13,557 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 7c2c880a-86be-4f24-872b-976f894b12ee, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:13,815 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:13,816 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:13,817 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 16dff680-12f3-4b16-ae17-c73f32d07419, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:13,818 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 16dff680-12f3-4b16-ae17-c73f32d07419, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:13,820 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 16dff680-12f3-4b16-ae17-c73f32d07419, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:14,364 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:14,365 - INFO - __tests__.alter_compression.668434  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594704, 'BlobCount': 640}}
2025-12-23 15:39:14,365 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:14,366 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 37793d43-2e08-4438-9fcf-09fde26590d5, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:14,367 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 37793d43-2e08-4438-9fcf-09fde26590d5, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:14,373 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 37793d43-2e08-4438-9fcf-09fde26590d5, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:16,250 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:16,251 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:16,252 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, e91dc501-a65e-4143-b989-71ddcca4a367, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:16,253 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, e91dc501-a65e-4143-b989-71ddcca4a367, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:16,254 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, e91dc501-a65e-4143-b989-71ddcca4a367, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:16,610 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:16,611 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:16,611 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, deb8aa73-b60e-4570-8963-268ef462cb5d, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:16,612 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, deb8aa73-b60e-4570-8963-268ef462cb5d, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:16,614 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, deb8aa73-b60e-4570-8963-268ef462cb5d, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:17,190 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:17,191 - INFO - __tests__.alter_compression.668434  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913888, 'BlobCount': 768}}
2025-12-23 15:39:17,191 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:17,192 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 74062e05-f9df-4744-813b-999023e84802, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:17,193 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 74062e05-f9df-4744-813b-999023e84802, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:17,194 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 74062e05-f9df-4744-813b-999023e84802, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:19,122 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:19,123 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:19,124 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, ede8cf25-7164-4b1f-bc23-f14432a137f8, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:19,124 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, ede8cf25-7164-4b1f-bc23-f14432a137f8, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:19,126 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, ede8cf25-7164-4b1f-bc23-f14432a137f8, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:19,453 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:19,453 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:19,454 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 9634906c-f417-409a-94a1-e2592925e8f3, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:19,455 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 9634906c-f417-409a-94a1-e2592925e8f3, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:19,456 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 9634906c-f417-409a-94a1-e2592925e8f3, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:20,080 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:20,081 - INFO - __tests__.alter_compression.668434  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233024, 'BlobCount': 896}}
2025-12-23 15:39:20,081 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:20,082 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 8d2d2f91-cc56-42a4-bcaf-cee3f0438cc1, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:20,083 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 8d2d2f91-cc56-42a4-bcaf-cee3f0438cc1, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:20,084 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 8d2d2f91-cc56-42a4-bcaf-cee3f0438cc1, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:21,808 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:21,809 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:21,810 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 71768a12-405e-43ae-8108-49778196c543, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:21,811 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 71768a12-405e-43ae-8108-49778196c543, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:21,812 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 71768a12-405e-43ae-8108-49778196c543, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:22,204 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:22,205 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:22,206 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 8cb09f05-8157-4e47-acf7-ea6e5df1ac99, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:22,207 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 8cb09f05-8157-4e47-acf7-ea6e5df1ac99, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:22,208 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 8cb09f05-8157-4e47-acf7-ea6e5df1ac99, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:22,925 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:22,926 - INFO - __tests__.alter_compression.668434  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552512, 'BlobCount': 1024}}
2025-12-23 15:39:22,926 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:22,927 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 31f66471-69d4-4cfa-b5ee-01eea0bcf9e2, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:22,928 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 31f66471-69d4-4cfa-b5ee-01eea0bcf9e2, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:22,929 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 31f66471-69d4-4cfa-b5ee-01eea0bcf9e2, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:24,908 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:24,909 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:24,909 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 5dc4932b-224e-44b8-b797-be0696d5ae13, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:24,910 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 5dc4932b-224e-44b8-b797-be0696d5ae13, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:24,912 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 5dc4932b-224e-44b8-b797-be0696d5ae13, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:25,237 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:25,238 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:25,238 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 7f30bc49-7f1a-437d-9567-f769771b5325, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:25,239 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 7f30bc49-7f1a-437d-9567-f769771b5325, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:25,240 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 7f30bc49-7f1a-437d-9567-f769771b5325, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:25,942 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:25,942 - INFO - __tests__.alter_compression.668434  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871520, 'BlobCount': 1152}}
2025-12-23 15:39:25,943 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:25,944 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 273824ea-ff98-4858-9473-63f7ec260347, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:25,945 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 273824ea-ff98-4858-9473-63f7ec260347, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:25,947 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 273824ea-ff98-4858-9473-63f7ec260347, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:27,725 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:27,726 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:27,727 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 1ed7f72f-d28f-4bfd-88b9-95eccbb3def7, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:27,728 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 1ed7f72f-d28f-4bfd-88b9-95eccbb3def7, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:27,729 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 1ed7f72f-d28f-4bfd-88b9-95eccbb3def7, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:28,041 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:28,041 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:28,042 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, ba52cf20-c171-4ed0-a5b1-546e86767a30, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:28,043 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, ba52cf20-c171-4ed0-a5b1-546e86767a30, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:28,045 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, ba52cf20-c171-4ed0-a5b1-546e86767a30, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:28,797 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:28,797 - INFO - __tests__.alter_compression.668434  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191024, 'BlobCount': 1280}}
2025-12-23 15:39:28,798 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:28,799 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 46826601-2da4-444a-a486-9014ccd521bb, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:28,799 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 46826601-2da4-444a-a486-9014ccd521bb, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_10_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:28,805 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 46826601-2da4-444a-a486-9014ccd521bb, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:30,086 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:30,086 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:30,087 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, d743df03-975f-4be9-9554-b726fbfd0f85, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:30,087 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, d743df03-975f-4be9-9554-b726fbfd0f85, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:30,088 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, d743df03-975f-4be9-9554-b726fbfd0f85, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:30,502 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:30,502 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:30,503 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 29f518ae-39bd-4a00-a572-07de74577491, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:30,503 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 29f518ae-39bd-4a00-a572-07de74577491, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:30,505 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 29f518ae-39bd-4a00-a572-07de74577491, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:30,783 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:30,783 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:30,784 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, ef5b5a6e-f356-443e-9e09-07c0e4a7f89d, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:30,784 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, ef5b5a6e-f356-443e-9e09-07c0e4a7f89d, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:30,786 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, ef5b5a6e-f356-443e-9e09-07c0e4a7f89d, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:34,615 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:34,977 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668434  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:39:34,978 - DEBUG - ydb.connection.668434  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:39:34,996 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668434  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:39:34,997 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ListEndpoints, 216b5656-525e-45fe-a3c2-6cc3a7fca6b4, localhost:4073): creating call state
2025-12-23 15:39:34,997 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ListEndpoints, 216b5656-525e-45fe-a3c2-6cc3a7fca6b4, localhost:4073): request = { database: "/Root" }
2025-12-23 15:39:35,003 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ListEndpoints, 216b5656-525e-45fe-a3c2-6cc3a7fca6b4, localhost:4073): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-icmf7pzugu.auto.internal" port: 4073 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-12-23 15:39:35,004 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668434  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-icmf7pzugu.auto.internal:4073, location 1, ssl: False>]>
2025-12-23 15:39:35,005 - DEBUG - ydb.connection.668434  - close: Closing channel for endpoint localhost:4073
2025-12-23 15:39:44,648 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:44,649 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 87e5bc89-ce9c-4c40-be44-16dfeb4ef285, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:44,650 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 87e5bc89-ce9c-4c40-be44-16dfeb4ef285, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:44,651 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 87e5bc89-ce9c-4c40-be44-16dfeb4ef285, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:47,905 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:47,947 - INFO - root.668434  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1595512)
2025-12-23 15:39:47,947 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:47,948 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, dcef6b63-9c8b-457b-b4b7-a1038fcb5ab6, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:47,949 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, dcef6b63-9c8b-457b-b4b7-a1038fcb5ab6, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:47,951 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, dcef6b63-9c8b-457b-b4b7-a1038fcb5ab6, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:48,170 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:48,171 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:39:48,171 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, e11ba571-e1b0-4c2e-bb70-353c514fc578, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:39:48,172 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, e11ba571-e1b0-4c2e-bb70-353c514fc578, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:48,174 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, e11ba571-e1b0-4c2e-bb70-353c514fc578, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:51,183 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:40:01,248 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:40:01,249 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, de592dfe-1238-4a1f-8439-6b6b4fe7b388, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:40:01,249 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, de592dfe-1238-4a1f-8439-6b6b4fe7b388, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:40:01,251 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, de592dfe-1238-4a1f-8439-6b6b4fe7b388, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:03,991 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:40:14,011 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:40:14,011 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, e5241b8b-61a0-40df-b67b-25aefc9028ba, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:40:14,012 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, e5241b8b-61a0-40df-b67b-25aefc9028ba, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:40:14,013 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, e5241b8b-61a0-40df-b67b-25aefc9028ba, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:14,645 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:40:24,652 - DEBUG - ydb.query.pool.668434  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:40:24,653 - DEBUG - ydb.connection.668434  - _prepare_call: RpcState(ExecuteQuery, 69597183-90d3-4ab7-a43c-d4f7d434e60c, ghrun-icmf7pzugu.auto.internal:4073): creating call state
2025-12-23 15:40:24,654 - DEBUG - ydb.connection.668434  - _log_request: RpcState(ExecuteQuery, 69597183-90d3-4ab7-a43c-d4f7d434e60c, ghrun-icmf7pzugu.auto.internal:4073): request = { session_id: "ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:40:24,655 - DEBUG - ydb.connection.668434  - _log_response: RpcState(ExecuteQuery, 69597183-90d3-4ab7-a43c-d4f7d434e60c, ghrun-icmf7pzugu.auto.internal:4073): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:25,323 - DEBUG - ydb.query.pool.668434  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E4NWE2ZTgtYTcyMjIyNC03MTU3YjQ2MS03YmJkYzkyOA==
2025-12-23 15:40:25,326 - INFO - root.668434  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1465240)
2025-12-23 15:40:25,326 - INFO - root.668434  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_10_compression` 8092160 / 1465240: 5.522753951571073
2025-12-23 15:40:25,328 - INFO - ya.test.668434  - pytest_runtest_teardown: Test teardown
