2025-12-23 19:00:09,912 - INFO - ya.test.1602925  - pytest_runtest_setup: ####################################################################################################
2025-12-23 19:00:09,913 - INFO - ya.test.1602925  - pytest_runtest_setup: test_all_supported_compression[zstd_9_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 9]
2025-12-23 19:00:09,913 - INFO - ya.test.1602925  - pytest_runtest_setup: ####################################################################################################
2025-12-23 19:00:09,913 - INFO - ya.test.1602925  - pytest_runtest_setup: Test setup
2025-12-23 19:00:09,916 - INFO - ya.test.1602925  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_9_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 9])
2025-12-23 19:00:09,917 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:09,917 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 96998b56-7491-43cd-ab84-d2b8e1446003, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:09,918 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 96998b56-7491-43cd-ab84-d2b8e1446003, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_9_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 9)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:09,920 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 96998b56-7491-43cd-ab84-d2b8e1446003, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:13,406 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:13,407 - INFO - __tests__.alter_compression.1602925  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_9_compression created
2025-12-23 19:00:13,407 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:13,408 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 4dbf14d0-042f-42be-adf2-79ffb7188455, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:13,409 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 4dbf14d0-042f-42be-adf2-79ffb7188455, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_9_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:13,413 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 4dbf14d0-042f-42be-adf2-79ffb7188455, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:14,394 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:14,395 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:14,396 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 168f22f4-fbd6-461d-9cc3-23dc74017ede, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:14,396 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 168f22f4-fbd6-461d-9cc3-23dc74017ede, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:14,397 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 168f22f4-fbd6-461d-9cc3-23dc74017ede, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:16,040 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:16,041 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:16,041 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 7fcce3a5-51b5-4f9c-8a47-0e020870d97a, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:16,042 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 7fcce3a5-51b5-4f9c-8a47-0e020870d97a, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:16,044 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 7fcce3a5-51b5-4f9c-8a47-0e020870d97a, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:17,085 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:17,086 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:17,087 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 3fa1f546-2217-43a6-9383-14d841e436c1, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:17,088 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 3fa1f546-2217-43a6-9383-14d841e436c1, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:17,089 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 3fa1f546-2217-43a6-9383-14d841e436c1, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:19,059 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:19,059 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318048, 'BlobCount': 128}}
2025-12-23 19:00:19,060 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:19,060 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 793783ca-d9b5-4725-82ff-9188b4d01be8, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:19,061 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 793783ca-d9b5-4725-82ff-9188b4d01be8, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:19,062 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 793783ca-d9b5-4725-82ff-9188b4d01be8, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:20,564 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:20,564 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:20,565 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, b6e7dc50-c368-4ecc-9abe-eabeaaac29f1, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:20,566 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, b6e7dc50-c368-4ecc-9abe-eabeaaac29f1, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:20,568 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, b6e7dc50-c368-4ecc-9abe-eabeaaac29f1, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:20,916 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:20,917 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:20,917 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 8809f899-97da-4999-91e8-6002b7f0ba7c, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:20,918 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 8809f899-97da-4999-91e8-6002b7f0ba7c, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:20,920 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 8809f899-97da-4999-91e8-6002b7f0ba7c, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:21,442 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:21,443 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637344, 'BlobCount': 256}}
2025-12-23 19:00:21,443 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:21,444 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, d746cb2b-3ece-4909-b30a-3e8f97e8e24a, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:21,445 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, d746cb2b-3ece-4909-b30a-3e8f97e8e24a, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:21,447 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, d746cb2b-3ece-4909-b30a-3e8f97e8e24a, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:23,055 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:23,056 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:23,056 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 39ad61e9-87a6-4bd4-8a5d-c185085ff38b, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:23,057 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 39ad61e9-87a6-4bd4-8a5d-c185085ff38b, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:23,059 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 39ad61e9-87a6-4bd4-8a5d-c185085ff38b, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:23,343 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:23,343 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:23,344 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, c057a9cc-2362-40af-9e18-e73babaaf7af, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:23,345 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, c057a9cc-2362-40af-9e18-e73babaaf7af, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:23,346 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, c057a9cc-2362-40af-9e18-e73babaaf7af, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:23,766 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:23,767 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956384, 'BlobCount': 384}}
2025-12-23 19:00:23,768 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:23,768 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, e83e2a40-0cdc-487b-a6a6-956bc8717ce0, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:23,770 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, e83e2a40-0cdc-487b-a6a6-956bc8717ce0, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:23,771 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, e83e2a40-0cdc-487b-a6a6-956bc8717ce0, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:25,288 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:25,288 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:25,289 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 7235ad59-4194-4a53-8021-99574dad6c6d, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:25,290 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 7235ad59-4194-4a53-8021-99574dad6c6d, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:25,291 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 7235ad59-4194-4a53-8021-99574dad6c6d, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:25,530 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:25,531 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:25,532 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 73e2d5b7-cdf3-4646-a9b5-f13cdd141db2, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:25,533 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 73e2d5b7-cdf3-4646-a9b5-f13cdd141db2, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:25,534 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 73e2d5b7-cdf3-4646-a9b5-f13cdd141db2, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:25,963 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:25,964 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275744, 'BlobCount': 512}}
2025-12-23 19:00:25,965 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:25,965 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, e7ddb40f-97a7-40a4-80a5-7250c2fdce4f, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:25,966 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, e7ddb40f-97a7-40a4-80a5-7250c2fdce4f, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:25,968 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, e7ddb40f-97a7-40a4-80a5-7250c2fdce4f, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:27,351 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:27,352 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:27,353 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 9802b310-b8da-4a96-9d62-48397d2a60ed, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:27,354 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 9802b310-b8da-4a96-9d62-48397d2a60ed, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:27,355 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 9802b310-b8da-4a96-9d62-48397d2a60ed, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:27,625 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:27,626 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:27,626 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 960d2a62-292c-4a80-8c7b-4d462e999ef2, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:27,627 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 960d2a62-292c-4a80-8c7b-4d462e999ef2, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:27,629 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 960d2a62-292c-4a80-8c7b-4d462e999ef2, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:28,114 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:28,115 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594704, 'BlobCount': 640}}
2025-12-23 19:00:28,115 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:28,116 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 6a3a330c-69c4-42fe-804b-199fa69deb47, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:28,117 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 6a3a330c-69c4-42fe-804b-199fa69deb47, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:28,119 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 6a3a330c-69c4-42fe-804b-199fa69deb47, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:29,571 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:29,572 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:29,573 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, bb17b1bc-3dcd-4afc-9269-d8d753246df8, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:29,574 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, bb17b1bc-3dcd-4afc-9269-d8d753246df8, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:29,575 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, bb17b1bc-3dcd-4afc-9269-d8d753246df8, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:29,815 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:29,816 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:29,816 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, b524cac5-3c08-41ea-a1af-5d907c487c0d, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:29,817 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, b524cac5-3c08-41ea-a1af-5d907c487c0d, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:29,819 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, b524cac5-3c08-41ea-a1af-5d907c487c0d, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:30,329 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:30,330 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913888, 'BlobCount': 768}}
2025-12-23 19:00:30,330 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:30,331 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 3a9c6c79-a662-45f5-8324-6780a556bcf7, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:30,332 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 3a9c6c79-a662-45f5-8324-6780a556bcf7, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:30,333 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 3a9c6c79-a662-45f5-8324-6780a556bcf7, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:31,892 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:31,893 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:31,893 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 2f032b72-d2fc-431e-8da4-a1583c9dd1fe, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:31,894 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 2f032b72-d2fc-431e-8da4-a1583c9dd1fe, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:31,895 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 2f032b72-d2fc-431e-8da4-a1583c9dd1fe, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:32,150 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:32,151 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:32,152 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 9c201e3d-d0c0-4d4f-a967-69285d5471b5, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:32,153 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 9c201e3d-d0c0-4d4f-a967-69285d5471b5, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:32,154 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 9c201e3d-d0c0-4d4f-a967-69285d5471b5, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:32,708 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:32,709 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233024, 'BlobCount': 896}}
2025-12-23 19:00:32,710 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:32,710 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 53a9ddeb-e4d8-427f-a6bf-499c588aba00, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:32,711 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 53a9ddeb-e4d8-427f-a6bf-499c588aba00, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:32,712 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 53a9ddeb-e4d8-427f-a6bf-499c588aba00, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:34,720 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:34,720 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:34,721 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, b49f84b9-2f91-4828-9797-1efa4154790a, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:34,722 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, b49f84b9-2f91-4828-9797-1efa4154790a, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:34,723 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, b49f84b9-2f91-4828-9797-1efa4154790a, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:35,035 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:35,036 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:35,036 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 593626be-6053-4194-ae1f-49e1f385ed65, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:35,037 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 593626be-6053-4194-ae1f-49e1f385ed65, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:35,044 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 593626be-6053-4194-ae1f-49e1f385ed65, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:35,967 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:35,968 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552512, 'BlobCount': 1024}}
2025-12-23 19:00:35,968 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:35,969 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 09be3c77-0527-43d4-aafd-4d62f5a12f1f, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:35,970 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 09be3c77-0527-43d4-aafd-4d62f5a12f1f, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:35,975 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 09be3c77-0527-43d4-aafd-4d62f5a12f1f, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:38,263 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:38,264 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:38,265 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, a0e31c6f-f31c-4ba6-add0-addd4f7f9441, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:38,265 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, a0e31c6f-f31c-4ba6-add0-addd4f7f9441, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:38,275 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, a0e31c6f-f31c-4ba6-add0-addd4f7f9441, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:38,788 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:38,789 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:38,789 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, fbd99f70-fa57-4eac-8af5-0d47eb3257df, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:38,790 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, fbd99f70-fa57-4eac-8af5-0d47eb3257df, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:38,791 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, fbd99f70-fa57-4eac-8af5-0d47eb3257df, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:39,662 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:39,663 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871520, 'BlobCount': 1152}}
2025-12-23 19:00:39,664 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:39,664 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 8e1cf9b8-6271-4a0b-9ed5-c502de8f0985, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:39,665 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 8e1cf9b8-6271-4a0b-9ed5-c502de8f0985, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:39,667 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 8e1cf9b8-6271-4a0b-9ed5-c502de8f0985, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:41,816 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:41,817 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:41,818 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 74484685-d7b7-4b71-a9d6-4aa57defb03f, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:41,819 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 74484685-d7b7-4b71-a9d6-4aa57defb03f, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:41,820 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 74484685-d7b7-4b71-a9d6-4aa57defb03f, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:42,271 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:42,272 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:42,273 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 8f1233bc-a4f1-4283-8c77-eecf8f40b1ff, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:42,274 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 8f1233bc-a4f1-4283-8c77-eecf8f40b1ff, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:42,280 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 8f1233bc-a4f1-4283-8c77-eecf8f40b1ff, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:43,116 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:43,117 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191024, 'BlobCount': 1280}}
2025-12-23 19:00:43,118 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:43,118 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, bcbae353-9ec8-47fa-8f06-be514395ba5e, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:43,119 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, bcbae353-9ec8-47fa-8f06-be514395ba5e, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_9_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:43,121 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, bcbae353-9ec8-47fa-8f06-be514395ba5e, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:44,356 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:44,357 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:44,358 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, fd6555dd-b8f9-4d96-a2c1-925c4f518625, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:44,359 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, fd6555dd-b8f9-4d96-a2c1-925c4f518625, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:44,360 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, fd6555dd-b8f9-4d96-a2c1-925c4f518625, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:44,626 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:44,626 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:44,627 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, cb685fc3-5bea-4096-a38c-45d0ba9f6966, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:44,628 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, cb685fc3-5bea-4096-a38c-45d0ba9f6966, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:44,629 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, cb685fc3-5bea-4096-a38c-45d0ba9f6966, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:44,957 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:44,958 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:44,959 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, df520c4a-5b13-4852-bd39-4189978866ce, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:44,960 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, df520c4a-5b13-4852-bd39-4189978866ce, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:44,961 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, df520c4a-5b13-4852-bd39-4189978866ce, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:49,469 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:59,518 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:59,519 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, e10b6e00-2816-410c-9495-3ecf38071f0d, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:59,520 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, e10b6e00-2816-410c-9495-3ecf38071f0d, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:59,521 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, e10b6e00-2816-410c-9495-3ecf38071f0d, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:04,908 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:01:04,958 - INFO - root.1602925  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_9_compression`, volumes `value` (8000000, 1595512)
2025-12-23 19:01:04,959 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:01:04,960 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 9bbf932c-59de-48d1-95cf-1ba2fcbf9411, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:01:04,961 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 9bbf932c-59de-48d1-95cf-1ba2fcbf9411, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:01:04,962 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 9bbf932c-59de-48d1-95cf-1ba2fcbf9411, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:05,458 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:01:05,459 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:01:05,460 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 81cab454-f7a1-4841-ba6e-916e848ea4a5, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:01:05,460 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 81cab454-f7a1-4841-ba6e-916e848ea4a5, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:01:05,469 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 81cab454-f7a1-4841-ba6e-916e848ea4a5, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:11,390 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:01:21,510 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:01:21,511 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 29163781-20ab-4139-85e8-5c54488974ac, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:01:21,512 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 29163781-20ab-4139-85e8-5c54488974ac, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:01:21,513 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 29163781-20ab-4139-85e8-5c54488974ac, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:26,066 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:01:26,094 - INFO - root.1602925  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_9_compression`, volumes `value` (8000000, 1532376)
2025-12-23 19:01:26,097 - INFO - root.1602925  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_9_compression` 8092160 / 1532376: 5.280792703618433
2025-12-23 19:01:26,099 - INFO - ya.test.1602925  - pytest_runtest_teardown: Test teardown
2025-12-23 19:01:26,101 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(DeleteSession, 14fd280a-0c02-4823-a7a9-29a3b03e182a, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:01:26,102 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(DeleteSession, 14fd280a-0c02-4823-a7a9-29a3b03e182a, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" }
2025-12-23 19:01:26,109 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(DeleteSession, 14fd280a-0c02-4823-a7a9-29a3b03e182a, ghrun-ou4bsyjxme.auto.internal:19900): response = { status: SUCCESS }
2025-12-23 19:01:26,111 - DEBUG - ydb.query.pool.1602925  - stop: All session were deleted.
2025-12-23 19:01:26,112 - DEBUG - ydb.topic.1602925  - close: Close topic client
2025-12-23 19:01:26,112 - DEBUG - ydb.topic.1602925  - close: Topic client was closed
2025-12-23 19:01:26,113 - DEBUG - ydb.connection.1602925  - close: Closing channel for endpoint localhost:19900
2025-12-23 19:01:26,115 - DEBUG - ydb.connection.1602925  - close: Closing channel for endpoint ghrun-ou4bsyjxme.auto.internal:19900
2025-12-23 19:01:26,127 - DEBUG - ydb.connection.1602925  - _rpc_error_handler: RpcState(AttachSession, 4c5b1b5a-5162-4132-afb4-c875718cb895, ghrun-ou4bsyjxme.auto.internal:19900): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-23 19:01:26,130 - INFO - ydb.pool.Discovery.1602925  - run: Successfully terminated discovery process
2025-12-23 19:01:38,145 - INFO - ydb.tests.library.harness.kikimr_runner.1602925  - stop: Stopped node localhost:19900/1
2025-12-23 19:01:38,151 - DEBUG - library.python.filelock.1602925  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4130
2025-12-23 19:01:38,151 - DEBUG - library.python.filelock.1602925  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61190
2025-12-23 19:01:38,152 - DEBUG - library.python.filelock.1602925  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8233
2025-12-23 19:01:38,152 - DEBUG - library.python.filelock.1602925  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19900
2025-12-23 19:01:38,152 - DEBUG - library.python.filelock.1602925  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26861
