2025-11-21 17:40:41,527 - INFO - ya.test.675612  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:40:41,528 - INFO - ya.test.675612  - pytest_runtest_setup: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16]
2025-11-21 17:40:41,528 - INFO - ya.test.675612  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:40:41,529 - INFO - ya.test.675612  - pytest_runtest_setup: Test setup
2025-11-21 17:40:41,531 - INFO - ya.test.675612  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16])
2025-11-21 17:40:41,532 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:41,533 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 1b8f8808-8482-493d-8ffa-f01e97281c4c, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:40:41,534 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 1b8f8808-8482-493d-8ffa-f01e97281c4c, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_16_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 16)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:41,535 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 1b8f8808-8482-493d-8ffa-f01e97281c4c, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:45,412 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:45,413 - INFO - __tests__.alter_compression.675612  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_16_compression created
2025-11-21 17:40:45,414 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:45,414 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 0460332b-e560-4da7-8f9a-16c05cad05d2, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:40:45,415 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 0460332b-e560-4da7-8f9a-16c05cad05d2, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:45,421 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 0460332b-e560-4da7-8f9a-16c05cad05d2, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:46,526 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:46,527 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:46,528 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 87e4a9d6-672e-4ec0-a5fe-b2d320a74f1d, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:40:46,529 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 87e4a9d6-672e-4ec0-a5fe-b2d320a74f1d, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:46,531 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 87e4a9d6-672e-4ec0-a5fe-b2d320a74f1d, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:52,970 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:52,971 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:52,971 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, c33f7f9b-f7c4-442e-8c47-93d5d582542c, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:40:52,972 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, c33f7f9b-f7c4-442e-8c47-93d5d582542c, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:52,975 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, c33f7f9b-f7c4-442e-8c47-93d5d582542c, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:54,367 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:54,368 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:54,368 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 40ae9d85-532d-4732-bf2c-a35d241e994e, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:40:54,369 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 40ae9d85-532d-4732-bf2c-a35d241e994e, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:54,370 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 40ae9d85-532d-4732-bf2c-a35d241e994e, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:56,680 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:56,681 - INFO - __tests__.alter_compression.675612  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-21 17:40:56,682 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:40:56,682 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 271a7e89-8858-4b1e-a86e-13f46070614a, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:40:56,684 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 271a7e89-8858-4b1e-a86e-13f46070614a, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:56,685 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 271a7e89-8858-4b1e-a86e-13f46070614a, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:02,403 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:02,404 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:02,405 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 18bb6266-15aa-4f11-8549-1aa979e04642, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:02,405 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 18bb6266-15aa-4f11-8549-1aa979e04642, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:02,408 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 18bb6266-15aa-4f11-8549-1aa979e04642, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:02,761 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:02,762 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:02,763 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 7a53be8f-20f9-4995-a010-d7ef17aa9cb9, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:02,764 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 7a53be8f-20f9-4995-a010-d7ef17aa9cb9, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:02,767 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 7a53be8f-20f9-4995-a010-d7ef17aa9cb9, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:03,238 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:03,239 - INFO - __tests__.alter_compression.675612  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-21 17:41:03,240 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:03,240 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 54bd0cf4-943e-4a25-a487-4f34a2c5650b, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:03,241 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 54bd0cf4-943e-4a25-a487-4f34a2c5650b, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:03,243 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 54bd0cf4-943e-4a25-a487-4f34a2c5650b, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:08,188 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:08,189 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:08,190 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 3a22a33d-1927-4754-8749-f9b53b18b36d, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:08,191 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 3a22a33d-1927-4754-8749-f9b53b18b36d, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:08,193 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 3a22a33d-1927-4754-8749-f9b53b18b36d, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:08,561 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:08,562 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:08,563 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 0b2abe2d-d98f-422e-8c09-3588742bf9c1, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:08,563 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 0b2abe2d-d98f-422e-8c09-3588742bf9c1, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:08,564 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 0b2abe2d-d98f-422e-8c09-3588742bf9c1, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:09,116 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:09,116 - INFO - __tests__.alter_compression.675612  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-21 17:41:09,117 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:09,118 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, ebb82d91-97c9-46b4-86ef-f69847f8c8f8, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:09,119 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, ebb82d91-97c9-46b4-86ef-f69847f8c8f8, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:09,121 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, ebb82d91-97c9-46b4-86ef-f69847f8c8f8, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:13,218 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:13,219 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:13,219 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, a2bdaf3a-f31f-4f1d-b36e-7ac7d607e368, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:13,220 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, a2bdaf3a-f31f-4f1d-b36e-7ac7d607e368, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:13,221 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, a2bdaf3a-f31f-4f1d-b36e-7ac7d607e368, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:13,535 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:13,536 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:13,537 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 22210056-e6b4-4908-bfd3-a765cdb77288, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:13,537 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 22210056-e6b4-4908-bfd3-a765cdb77288, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:13,538 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 22210056-e6b4-4908-bfd3-a765cdb77288, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:14,010 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:14,011 - INFO - __tests__.alter_compression.675612  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-21 17:41:14,012 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:14,012 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 11114660-3aee-4a0f-a574-853567951fc1, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:14,013 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 11114660-3aee-4a0f-a574-853567951fc1, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:14,015 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 11114660-3aee-4a0f-a574-853567951fc1, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:16,594 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675612  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 17:41:16,595 - DEBUG - ydb.connection.675612  - 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 17:41:16,606 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675612  - context_resolve: Resolving endpoints for database /Root
2025-11-21 17:41:16,606 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ListEndpoints, a56964d0-c45a-484b-b890-5bb1a2283f3a, localhost:30976): creating call state
2025-11-21 17:41:16,607 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ListEndpoints, a56964d0-c45a-484b-b890-5bb1a2283f3a, localhost:30976): request = { database: "/Root" }
2025-11-21 17:41:16,617 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ListEndpoints, a56964d0-c45a-484b-b890-5bb1a2283f3a, localhost:30976): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-zme4sewyte.auto.internal" port: 30976 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 17:41:16,618 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675612  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-zme4sewyte.auto.internal:30976, location 1, ssl: False>]>
2025-11-21 17:41:16,619 - DEBUG - ydb.connection.675612  - close: Closing channel for endpoint localhost:30976
2025-11-21 17:41:18,073 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:18,074 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:18,075 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 6d81218b-c773-4c8c-9a2f-fcc9bf9a811a, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:18,075 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 6d81218b-c773-4c8c-9a2f-fcc9bf9a811a, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:18,077 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 6d81218b-c773-4c8c-9a2f-fcc9bf9a811a, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:18,366 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:18,367 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:18,367 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 10604778-92a1-4cd3-b0a3-5898b6d1c14c, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:18,368 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 10604778-92a1-4cd3-b0a3-5898b6d1c14c, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:18,375 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 10604778-92a1-4cd3-b0a3-5898b6d1c14c, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:18,838 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:18,838 - INFO - __tests__.alter_compression.675612  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-21 17:41:18,839 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:18,839 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 57e722f1-6df3-480a-81be-0af4ba97db4a, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:18,840 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 57e722f1-6df3-480a-81be-0af4ba97db4a, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:18,842 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 57e722f1-6df3-480a-81be-0af4ba97db4a, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:22,978 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:22,979 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:22,979 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 025fa07b-d924-465f-80c1-691506fd894d, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:22,980 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 025fa07b-d924-465f-80c1-691506fd894d, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:22,982 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 025fa07b-d924-465f-80c1-691506fd894d, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:23,306 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:23,307 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:23,308 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 8ea97c05-6480-499a-8133-513f6d6c63ce, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:23,309 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 8ea97c05-6480-499a-8133-513f6d6c63ce, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:23,310 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 8ea97c05-6480-499a-8133-513f6d6c63ce, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:23,899 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:23,900 - INFO - __tests__.alter_compression.675612  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-21 17:41:23,901 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:23,901 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 1ad83c62-0b0e-42b0-b26e-4373f96606f9, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:23,902 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 1ad83c62-0b0e-42b0-b26e-4373f96606f9, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:23,904 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 1ad83c62-0b0e-42b0-b26e-4373f96606f9, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:28,371 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:28,372 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:28,372 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 9de87d17-f118-47fc-a45d-7aca4bfcfd63, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:28,373 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 9de87d17-f118-47fc-a45d-7aca4bfcfd63, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:28,375 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 9de87d17-f118-47fc-a45d-7aca4bfcfd63, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:28,720 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:28,722 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:28,722 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, cb47698d-3fff-4b09-90ae-c27980cdbe68, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:28,724 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, cb47698d-3fff-4b09-90ae-c27980cdbe68, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:28,725 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, cb47698d-3fff-4b09-90ae-c27980cdbe68, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:29,324 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:29,324 - INFO - __tests__.alter_compression.675612  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-21 17:41:29,325 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:29,326 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 11f4fe3b-b126-4c4f-9cc5-fee934eed534, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:29,327 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 11f4fe3b-b126-4c4f-9cc5-fee934eed534, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:29,329 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 11f4fe3b-b126-4c4f-9cc5-fee934eed534, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:33,998 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:33,999 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:34,000 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 47293427-28ad-4785-a999-decf090d6846, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:34,001 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 47293427-28ad-4785-a999-decf090d6846, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:34,002 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 47293427-28ad-4785-a999-decf090d6846, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:34,412 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:34,413 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:34,414 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 48f8c73c-1777-4b73-ac5c-ece0196271d8, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:34,415 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 48f8c73c-1777-4b73-ac5c-ece0196271d8, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:34,423 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 48f8c73c-1777-4b73-ac5c-ece0196271d8, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:35,350 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:35,351 - INFO - __tests__.alter_compression.675612  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-21 17:41:35,352 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:35,352 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, a14d0505-0790-4cdf-a4b6-021d986ac7d8, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:35,353 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, a14d0505-0790-4cdf-a4b6-021d986ac7d8, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:35,355 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, a14d0505-0790-4cdf-a4b6-021d986ac7d8, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:39,943 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:39,944 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:39,945 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 4b051104-7200-4df1-8880-833f13b55cd3, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:39,946 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 4b051104-7200-4df1-8880-833f13b55cd3, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:39,947 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 4b051104-7200-4df1-8880-833f13b55cd3, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:40,293 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:40,294 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:40,294 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 96da2a7f-023d-4439-893d-318d85b14d63, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:40,295 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 96da2a7f-023d-4439-893d-318d85b14d63, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:40,297 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 96da2a7f-023d-4439-893d-318d85b14d63, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:40,990 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:40,991 - INFO - __tests__.alter_compression.675612  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-21 17:41:40,991 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:40,992 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 27cded6c-f878-4342-bf77-7f61915dc5fa, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:40,993 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 27cded6c-f878-4342-bf77-7f61915dc5fa, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:40,994 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 27cded6c-f878-4342-bf77-7f61915dc5fa, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:46,119 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:46,120 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:46,121 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 36347fff-16f7-4d18-b3b4-a55b11d63ccc, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:46,121 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 36347fff-16f7-4d18-b3b4-a55b11d63ccc, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:46,123 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 36347fff-16f7-4d18-b3b4-a55b11d63ccc, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:46,485 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:46,485 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:46,486 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 2d152b4a-87a1-4eac-b51a-ee5aedc05c8f, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:46,487 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 2d152b4a-87a1-4eac-b51a-ee5aedc05c8f, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:46,489 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 2d152b4a-87a1-4eac-b51a-ee5aedc05c8f, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:47,322 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:47,323 - INFO - __tests__.alter_compression.675612  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-21 17:41:47,323 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:47,324 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, a7fa5629-eb6c-45cc-a6de-3b5b379b90b2, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:47,325 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, a7fa5629-eb6c-45cc-a6de-3b5b379b90b2, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:47,327 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, a7fa5629-eb6c-45cc-a6de-3b5b379b90b2, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:48,931 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:48,932 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:48,933 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 9c4fb004-f7e2-41ef-ab23-93a43f4ecef9, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:48,934 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 9c4fb004-f7e2-41ef-ab23-93a43f4ecef9, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:48,935 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 9c4fb004-f7e2-41ef-ab23-93a43f4ecef9, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:49,343 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:49,344 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:49,345 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 64a96719-039c-4a54-a21d-fccd177cf07e, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:49,346 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 64a96719-039c-4a54-a21d-fccd177cf07e, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:49,348 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 64a96719-039c-4a54-a21d-fccd177cf07e, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:49,652 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:49,653 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:41:49,653 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 01ae0be5-c68c-4d69-8b77-a184ce3e4f37, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:41:49,654 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 01ae0be5-c68c-4d69-8b77-a184ce3e4f37, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:49,662 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 01ae0be5-c68c-4d69-8b77-a184ce3e4f37, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:53,832 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:42:03,882 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:42:03,883 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, e7473ef2-04e1-40c3-8b56-52e8962628e4, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:42:03,884 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, e7473ef2-04e1-40c3-8b56-52e8962628e4, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:03,891 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, e7473ef2-04e1-40c3-8b56-52e8962628e4, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:07,437 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:42:07,480 - INFO - root.675612  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1595704)
2025-11-21 17:42:07,481 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:42:07,481 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 35ace900-5fd6-491c-ab42-7596de44c452, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:42:07,482 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 35ace900-5fd6-491c-ab42-7596de44c452, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:07,483 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 35ace900-5fd6-491c-ab42-7596de44c452, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:07,784 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:42:07,785 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:42:07,785 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, e0bd9a8f-5dfb-46b3-8c7f-b9d22f658363, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:42:07,786 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, e0bd9a8f-5dfb-46b3-8c7f-b9d22f658363, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:07,787 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, e0bd9a8f-5dfb-46b3-8c7f-b9d22f658363, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:10,933 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:42:21,005 - DEBUG - ydb.query.pool.675612  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:42:21,006 - DEBUG - ydb.connection.675612  - _prepare_call: RpcState(ExecuteQuery, 22dd417a-724a-4b2e-a391-c5b57e0c0e07, ghrun-zme4sewyte.auto.internal:30976): creating call state
2025-11-21 17:42:21,007 - DEBUG - ydb.connection.675612  - _log_request: RpcState(ExecuteQuery, 22dd417a-724a-4b2e-a391-c5b57e0c0e07, ghrun-zme4sewyte.auto.internal:30976): request = { session_id: "ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:42:21,008 - DEBUG - ydb.connection.675612  - _log_response: RpcState(ExecuteQuery, 22dd417a-724a-4b2e-a391-c5b57e0c0e07, ghrun-zme4sewyte.auto.internal:30976): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:42:23,838 - DEBUG - ydb.query.pool.675612  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWE5YjJiMDQtYjg0Njg4ZWQtNWE3ZTY0YWQtYmNkNmFlNTY=
2025-11-21 17:42:23,872 - INFO - root.675612  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1595704)
2025-11-21 17:42:23,872 - INFO - root.675612  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_16_compression` 8092160 / 1595704: 5.071216215538722
2025-11-21 17:42:23,874 - INFO - ya.test.675612  - pytest_runtest_teardown: Test teardown
