2025-12-09 12:52:45,943 - INFO - ya.test.1986964  - pytest_runtest_setup: ####################################################################################################
2025-12-09 12:52:45,943 - INFO - ya.test.1986964  - pytest_runtest_setup: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10]
2025-12-09 12:52:45,944 - INFO - ya.test.1986964  - pytest_runtest_setup: ####################################################################################################
2025-12-09 12:52:45,944 - INFO - ya.test.1986964  - pytest_runtest_setup: Test setup
2025-12-09 12:52:45,946 - INFO - ya.test.1986964  - 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-09 12:52:45,946 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:45,947 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 74684dd7-528e-47e6-aa02-d0f8b9bb9abc, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:45,948 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 74684dd7-528e-47e6-aa02-d0f8b9bb9abc, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:45,949 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 74684dd7-528e-47e6-aa02-d0f8b9bb9abc, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:48,874 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:48,874 - INFO - __tests__.alter_compression.1986964  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_10_compression created
2025-12-09 12:52:48,875 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:48,876 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, a5192c06-cf50-4e4f-89a3-9c9b078196a0, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:48,877 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, a5192c06-cf50-4e4f-89a3-9c9b078196a0, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:48,878 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, a5192c06-cf50-4e4f-89a3-9c9b078196a0, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:49,663 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:49,664 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:49,665 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9651443f-4b94-41d0-a17a-bd141e2422bf, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:49,666 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9651443f-4b94-41d0-a17a-bd141e2422bf, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:49,669 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9651443f-4b94-41d0-a17a-bd141e2422bf, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:52,019 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:52,020 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:52,020 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 5b4e88fe-f6fd-47f7-ae17-a999bca320c1, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:52,021 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 5b4e88fe-f6fd-47f7-ae17-a999bca320c1, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:52,022 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 5b4e88fe-f6fd-47f7-ae17-a999bca320c1, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:52,883 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:52,884 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:52,885 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, e69fe788-884b-4202-85a3-36d70488edc1, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:52,885 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, e69fe788-884b-4202-85a3-36d70488edc1, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:52,887 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, e69fe788-884b-4202-85a3-36d70488edc1, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:54,751 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:54,752 - INFO - __tests__.alter_compression.1986964  - 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-09 12:52:54,752 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:54,753 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 582b5e5b-107e-4b12-9d1c-782fe0390f3c, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:54,754 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 582b5e5b-107e-4b12-9d1c-782fe0390f3c, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:54,755 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 582b5e5b-107e-4b12-9d1c-782fe0390f3c, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:56,906 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:56,907 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:56,908 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 8d48eed6-977e-4433-a2f3-66095b064b75, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:56,909 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 8d48eed6-977e-4433-a2f3-66095b064b75, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:56,910 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 8d48eed6-977e-4433-a2f3-66095b064b75, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:57,222 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:57,222 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:57,223 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 18eb794d-375d-4ec5-ae07-4cc4ed4339c3, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:57,224 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 18eb794d-375d-4ec5-ae07-4cc4ed4339c3, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:57,226 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 18eb794d-375d-4ec5-ae07-4cc4ed4339c3, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:57,713 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:57,714 - INFO - __tests__.alter_compression.1986964  - 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-09 12:52:57,714 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:57,715 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, a0c2f098-dd99-4fb4-8960-58d270a37303, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:57,716 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, a0c2f098-dd99-4fb4-8960-58d270a37303, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:57,717 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, a0c2f098-dd99-4fb4-8960-58d270a37303, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:59,486 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:59,487 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:59,487 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 86a0b448-8336-4946-bc33-e89a79ce620a, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:59,488 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 86a0b448-8336-4946-bc33-e89a79ce620a, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:59,490 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 86a0b448-8336-4946-bc33-e89a79ce620a, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:59,810 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:59,811 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:59,812 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 761a358e-c829-467a-bb7c-32d5d0d971c5, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:59,812 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 761a358e-c829-467a-bb7c-32d5d0d971c5, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:52:59,813 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 761a358e-c829-467a-bb7c-32d5d0d971c5, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:00,381 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:00,382 - INFO - __tests__.alter_compression.1986964  - 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-09 12:53:00,382 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:00,383 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 689f69fa-d049-44a0-bf3a-5df3aeaf9508, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:00,384 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 689f69fa-d049-44a0-bf3a-5df3aeaf9508, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:00,397 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 689f69fa-d049-44a0-bf3a-5df3aeaf9508, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:02,598 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:02,599 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:02,599 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 97ada357-1c21-49ef-aeaf-59de29a87035, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:02,600 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 97ada357-1c21-49ef-aeaf-59de29a87035, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:02,602 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 97ada357-1c21-49ef-aeaf-59de29a87035, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:02,954 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:02,955 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:02,955 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9793a67c-11e7-4c61-88a1-d6b50272bfdd, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:02,956 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9793a67c-11e7-4c61-88a1-d6b50272bfdd, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:02,962 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9793a67c-11e7-4c61-88a1-d6b50272bfdd, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:03,821 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:03,822 - INFO - __tests__.alter_compression.1986964  - 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-09 12:53:03,823 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:03,823 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 32ea7c86-a397-47f6-bb19-fc058e8f132d, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:03,825 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 32ea7c86-a397-47f6-bb19-fc058e8f132d, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:03,826 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 32ea7c86-a397-47f6-bb19-fc058e8f132d, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:03,911 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 12:53:03,912 - DEBUG - ydb.connection.1986964  - 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-12-09 12:53:03,934 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - context_resolve: Resolving endpoints for database /Root
2025-12-09 12:53:03,935 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ListEndpoints, 1d92bde1-cc2f-437d-a8e0-3f2cafff3bba, localhost:63675): creating call state
2025-12-09 12:53:03,936 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ListEndpoints, 1d92bde1-cc2f-437d-a8e0-3f2cafff3bba, localhost:63675): request = { database: "/Root" }
2025-12-09 12:53:03,948 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ListEndpoints, 1d92bde1-cc2f-437d-a8e0-3f2cafff3bba, localhost:63675): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7wapoxkjru.auto.internal" port: 63675 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-09 12:53:03,950 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7wapoxkjru.auto.internal:63675, location 1, ssl: False>]>
2025-12-09 12:53:03,950 - DEBUG - ydb.connection.1986964  - close: Closing channel for endpoint localhost:63675
2025-12-09 12:53:05,790 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:05,791 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:05,792 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, d8d8e5d1-a838-4a80-b530-8bea49b47a81, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:05,793 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, d8d8e5d1-a838-4a80-b530-8bea49b47a81, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:05,794 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, d8d8e5d1-a838-4a80-b530-8bea49b47a81, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:06,190 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:06,191 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:06,192 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, f8627cf3-0b34-470a-9b1d-ee68bf64a6b4, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:06,193 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, f8627cf3-0b34-470a-9b1d-ee68bf64a6b4, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:06,194 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, f8627cf3-0b34-470a-9b1d-ee68bf64a6b4, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:06,863 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:06,864 - INFO - __tests__.alter_compression.1986964  - 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-09 12:53:06,864 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:06,865 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 794411a6-6da4-48c4-8cb5-3922cc153cc9, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:06,867 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 794411a6-6da4-48c4-8cb5-3922cc153cc9, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:06,868 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 794411a6-6da4-48c4-8cb5-3922cc153cc9, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:08,766 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:08,766 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:08,767 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 6caa34fc-c4c3-476b-ba7b-8a4f542883a3, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:08,768 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 6caa34fc-c4c3-476b-ba7b-8a4f542883a3, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:08,770 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 6caa34fc-c4c3-476b-ba7b-8a4f542883a3, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:09,134 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:09,135 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:09,136 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, e2b240c3-1f01-494f-a772-36a1088c66d6, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:09,137 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, e2b240c3-1f01-494f-a772-36a1088c66d6, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:09,138 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, e2b240c3-1f01-494f-a772-36a1088c66d6, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:09,886 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:09,887 - INFO - __tests__.alter_compression.1986964  - 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-09 12:53:09,888 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:09,888 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, edc6b484-75e6-4344-82c9-8544584366c3, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:09,889 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, edc6b484-75e6-4344-82c9-8544584366c3, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:09,891 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, edc6b484-75e6-4344-82c9-8544584366c3, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:11,527 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:11,528 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:11,529 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 40a24056-6b2d-4ca0-8a5c-96bf07637925, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:11,531 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 40a24056-6b2d-4ca0-8a5c-96bf07637925, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:11,533 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 40a24056-6b2d-4ca0-8a5c-96bf07637925, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:11,794 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:11,795 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:11,796 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 3f0b3396-cdcf-4609-a136-c8b0c4a7282b, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:11,797 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 3f0b3396-cdcf-4609-a136-c8b0c4a7282b, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:11,799 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 3f0b3396-cdcf-4609-a136-c8b0c4a7282b, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:12,420 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:12,420 - INFO - __tests__.alter_compression.1986964  - 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-09 12:53:12,421 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:12,421 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 27f396a1-5df3-4a2c-86d1-31839067552d, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:12,422 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 27f396a1-5df3-4a2c-86d1-31839067552d, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:12,424 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 27f396a1-5df3-4a2c-86d1-31839067552d, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:14,020 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:14,020 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:14,021 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 8e18b149-8dae-4036-91d6-edd329b8f9a4, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:14,022 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 8e18b149-8dae-4036-91d6-edd329b8f9a4, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:14,023 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 8e18b149-8dae-4036-91d6-edd329b8f9a4, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:14,549 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:14,550 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:14,551 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 8a43844f-6603-4d94-82f1-d62a48328352, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:14,552 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 8a43844f-6603-4d94-82f1-d62a48328352, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:14,553 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 8a43844f-6603-4d94-82f1-d62a48328352, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:15,642 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:15,643 - INFO - __tests__.alter_compression.1986964  - 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-09 12:53:15,643 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:15,644 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9c5115d1-7424-4557-b622-407d23d78ec0, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:15,645 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9c5115d1-7424-4557-b622-407d23d78ec0, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:15,647 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9c5115d1-7424-4557-b622-407d23d78ec0, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:18,046 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:18,046 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:18,047 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 783a3c49-7448-4533-a1de-5d947d9108ec, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:18,048 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 783a3c49-7448-4533-a1de-5d947d9108ec, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:18,051 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 783a3c49-7448-4533-a1de-5d947d9108ec, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:18,632 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:18,650 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:18,651 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9fd894f1-161f-4396-a3b0-d47ec110619e, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:18,652 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9fd894f1-161f-4396-a3b0-d47ec110619e, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:18,654 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9fd894f1-161f-4396-a3b0-d47ec110619e, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:19,502 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:19,503 - INFO - __tests__.alter_compression.1986964  - 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-09 12:53:19,503 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:19,504 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 667353e3-49ec-4028-ae16-b6808d9e251b, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:19,505 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 667353e3-49ec-4028-ae16-b6808d9e251b, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:19,507 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 667353e3-49ec-4028-ae16-b6808d9e251b, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:21,402 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:21,403 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:21,404 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, c63ded8a-084a-4769-a5fa-883dbd2a5ed8, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:21,404 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, c63ded8a-084a-4769-a5fa-883dbd2a5ed8, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:21,406 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, c63ded8a-084a-4769-a5fa-883dbd2a5ed8, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:21,698 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:21,698 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:21,699 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 98a851c2-f7d8-4ae6-a5eb-c9674e864cb0, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:21,700 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 98a851c2-f7d8-4ae6-a5eb-c9674e864cb0, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:21,702 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 98a851c2-f7d8-4ae6-a5eb-c9674e864cb0, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:22,542 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:22,543 - INFO - __tests__.alter_compression.1986964  - 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-09 12:53:22,544 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:22,544 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 582a9b01-93b8-47e1-b3ed-321e47fb5a05, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:22,545 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 582a9b01-93b8-47e1-b3ed-321e47fb5a05, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:22,547 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 582a9b01-93b8-47e1-b3ed-321e47fb5a05, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:23,882 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:23,883 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:23,883 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 733a7f67-c0b9-492f-96f3-0e94b1a84ead, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:23,884 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 733a7f67-c0b9-492f-96f3-0e94b1a84ead, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:23,888 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 733a7f67-c0b9-492f-96f3-0e94b1a84ead, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:24,390 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:24,391 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:24,391 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, e5e3821a-fd2a-4532-a5c0-9f1091e96bad, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:24,392 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, e5e3821a-fd2a-4532-a5c0-9f1091e96bad, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:24,394 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, e5e3821a-fd2a-4532-a5c0-9f1091e96bad, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:25,011 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:25,012 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:25,013 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 08d00dfa-9cee-4c6a-86f6-6982488b70ca, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:25,014 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 08d00dfa-9cee-4c6a-86f6-6982488b70ca, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:25,016 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 08d00dfa-9cee-4c6a-86f6-6982488b70ca, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:30,226 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:40,292 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:40,293 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 0f4eb5b7-11bf-4ffd-b950-e325d58ed77c, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:40,295 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 0f4eb5b7-11bf-4ffd-b950-e325d58ed77c, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:40,296 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 0f4eb5b7-11bf-4ffd-b950-e325d58ed77c, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:44,209 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:44,290 - INFO - root.1986964  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1595512)
2025-12-09 12:53:44,291 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:44,292 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 104fa34f-49d1-4552-bb31-456d0c811709, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:44,293 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 104fa34f-49d1-4552-bb31-456d0c811709, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:44,294 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 104fa34f-49d1-4552-bb31-456d0c811709, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:44,588 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:44,589 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:44,589 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9634f4f0-e694-4faf-9e0b-a53c6b82c0f5, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:44,590 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9634f4f0-e694-4faf-9e0b-a53c6b82c0f5, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:44,592 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9634f4f0-e694-4faf-9e0b-a53c6b82c0f5, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:48,086 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:58,142 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:53:58,143 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 7a053f4b-d14c-4c64-b632-07f185c2f964, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:53:58,144 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 7a053f4b-d14c-4c64-b632-07f185c2f964, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:53:58,145 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 7a053f4b-d14c-4c64-b632-07f185c2f964, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:53:59,001 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:09,065 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:09,066 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 599c2c04-f037-4018-ad76-56879146e45f, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:09,067 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 599c2c04-f037-4018-ad76-56879146e45f, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:54:09,068 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 599c2c04-f037-4018-ad76-56879146e45f, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:09,574 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:19,578 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:19,579 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 2d6453a1-02d3-4038-b62e-1c7c2120197e, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:19,580 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 2d6453a1-02d3-4038-b62e-1c7c2120197e, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:54:19,582 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 2d6453a1-02d3-4038-b62e-1c7c2120197e, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:20,268 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:20,271 - INFO - root.1986964  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1465240)
2025-12-09 12:54:20,272 - INFO - root.1986964  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_10_compression` 8092160 / 1465240: 5.522753951571073
2025-12-09 12:54:20,274 - INFO - ya.test.1986964  - pytest_runtest_teardown: Test teardown
