2025-12-23 19:04:36,287 - INFO - ya.test.1657981  - pytest_runtest_setup: ####################################################################################################
2025-12-23 19:04:36,288 - INFO - ya.test.1657981  - pytest_runtest_setup: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16]
2025-12-23 19:04:36,288 - INFO - ya.test.1657981  - pytest_runtest_setup: ####################################################################################################
2025-12-23 19:04:36,288 - INFO - ya.test.1657981  - pytest_runtest_setup: Test setup
2025-12-23 19:04:36,290 - INFO - ya.test.1657981  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16])
2025-12-23 19:04:36,291 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:36,292 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 01fb5fd1-85a9-4f4a-9c0f-03ad2789213d, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:04:36,293 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 01fb5fd1-85a9-4f4a-9c0f-03ad2789213d, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_16_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 16)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:36,295 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 01fb5fd1-85a9-4f4a-9c0f-03ad2789213d, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:43,066 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:43,067 - INFO - __tests__.alter_compression.1657981  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_16_compression created
2025-12-23 19:04:43,067 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:43,068 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 8a4b2eea-2d90-427f-a2c6-f9c2f6b0ecdc, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:04:43,069 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 8a4b2eea-2d90-427f-a2c6-f9c2f6b0ecdc, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:43,077 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 8a4b2eea-2d90-427f-a2c6-f9c2f6b0ecdc, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:44,340 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:44,341 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:44,341 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 6fe81ab3-b804-47e2-ba04-c72caa713a37, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:04:44,342 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 6fe81ab3-b804-47e2-ba04-c72caa713a37, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:44,349 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 6fe81ab3-b804-47e2-ba04-c72caa713a37, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:50,613 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:50,614 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:50,615 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, f0fb3cf0-ca84-4218-ae60-3c08f81a2e65, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:04:50,616 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, f0fb3cf0-ca84-4218-ae60-3c08f81a2e65, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:50,617 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, f0fb3cf0-ca84-4218-ae60-3c08f81a2e65, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:52,219 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:52,220 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:52,220 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 8bf73fb5-a583-45a1-9790-3f2d8bb6dd8d, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:04:52,221 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 8bf73fb5-a583-45a1-9790-3f2d8bb6dd8d, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:52,223 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 8bf73fb5-a583-45a1-9790-3f2d8bb6dd8d, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:54,628 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:54,629 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-12-23 19:04:54,629 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:04:54,630 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 2412e796-a95e-4b48-99da-6da7ea4d37d6, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:04:54,631 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 2412e796-a95e-4b48-99da-6da7ea4d37d6, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:54,637 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 2412e796-a95e-4b48-99da-6da7ea4d37d6, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:01,605 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:01,606 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:01,606 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 635fe739-4dca-4c5a-9446-c7ec30e5a256, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:01,607 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 635fe739-4dca-4c5a-9446-c7ec30e5a256, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:01,609 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 635fe739-4dca-4c5a-9446-c7ec30e5a256, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:02,300 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:02,301 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:02,301 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 2724f835-e715-47ea-a719-404efec32499, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:02,302 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 2724f835-e715-47ea-a719-404efec32499, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:02,312 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 2724f835-e715-47ea-a719-404efec32499, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:02,975 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:02,976 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-12-23 19:05:02,976 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:02,977 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 74b236c5-ead1-49be-af43-9dec7620bf9a, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:02,978 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 74b236c5-ead1-49be-af43-9dec7620bf9a, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:02,987 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 74b236c5-ead1-49be-af43-9dec7620bf9a, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:09,595 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:09,595 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:09,596 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 2fc7e0ea-0f10-422d-8423-855b2db3c0e5, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:09,597 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 2fc7e0ea-0f10-422d-8423-855b2db3c0e5, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:09,598 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 2fc7e0ea-0f10-422d-8423-855b2db3c0e5, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:10,107 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:10,108 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:10,108 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 2b0726d4-184c-4559-aefe-77a9cf9541e4, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:10,109 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 2b0726d4-184c-4559-aefe-77a9cf9541e4, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:10,111 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 2b0726d4-184c-4559-aefe-77a9cf9541e4, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:10,899 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:10,900 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-12-23 19:05:10,901 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:10,901 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 31e1d219-274f-477a-a93c-011a63cb6a33, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:10,902 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 31e1d219-274f-477a-a93c-011a63cb6a33, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:10,904 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 31e1d219-274f-477a-a93c-011a63cb6a33, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:16,185 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:16,186 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:16,186 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 785a6532-f91b-4afa-860a-2a49160be70f, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:16,187 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 785a6532-f91b-4afa-860a-2a49160be70f, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:16,189 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 785a6532-f91b-4afa-860a-2a49160be70f, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:16,535 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:16,536 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:16,536 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 022d3ceb-916f-41a5-bea1-c9dcf7384a3d, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:16,537 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 022d3ceb-916f-41a5-bea1-c9dcf7384a3d, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:16,540 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 022d3ceb-916f-41a5-bea1-c9dcf7384a3d, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:17,211 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:17,212 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-12-23 19:05:17,212 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:17,213 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, a1947c13-8424-4876-a47a-513545dd7100, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:17,214 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, a1947c13-8424-4876-a47a-513545dd7100, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:17,215 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, a1947c13-8424-4876-a47a-513545dd7100, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:23,235 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:23,236 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:23,237 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 09487dfe-056d-4849-ac81-99755c47f629, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:23,238 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 09487dfe-056d-4849-ac81-99755c47f629, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:23,239 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 09487dfe-056d-4849-ac81-99755c47f629, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:23,553 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:23,554 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:23,554 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 5ca6610d-661a-42f1-b34b-caab4d44131f, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:23,555 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 5ca6610d-661a-42f1-b34b-caab4d44131f, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:23,557 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 5ca6610d-661a-42f1-b34b-caab4d44131f, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:24,042 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:24,043 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-12-23 19:05:24,043 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:24,044 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 42e96b34-d210-4925-ac12-9c9653558f98, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:24,045 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 42e96b34-d210-4925-ac12-9c9653558f98, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:24,046 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 42e96b34-d210-4925-ac12-9c9653558f98, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:30,077 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:30,078 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:30,078 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, a9d02cf3-0ddc-42d9-923b-e3f96fe4e474, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:30,079 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, a9d02cf3-0ddc-42d9-923b-e3f96fe4e474, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:30,081 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, a9d02cf3-0ddc-42d9-923b-e3f96fe4e474, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:30,751 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:30,752 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:30,752 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, a70301ff-8999-42c4-8c14-3a80478d0183, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:30,753 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, a70301ff-8999-42c4-8c14-3a80478d0183, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:30,757 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, a70301ff-8999-42c4-8c14-3a80478d0183, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:31,839 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:31,839 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-12-23 19:05:31,840 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:31,840 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 1a8c7caa-1dfa-4e31-be37-d41af40b319f, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:31,841 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 1a8c7caa-1dfa-4e31-be37-d41af40b319f, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:31,843 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 1a8c7caa-1dfa-4e31-be37-d41af40b319f, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:38,863 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:38,863 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:38,864 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, d304ea6d-d0e9-4b58-bdb6-8bc2223005a3, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:38,865 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, d304ea6d-d0e9-4b58-bdb6-8bc2223005a3, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:38,874 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, d304ea6d-d0e9-4b58-bdb6-8bc2223005a3, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:39,488 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:39,489 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:39,490 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, a7ec25f2-1303-4945-b62d-cdd672538442, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:39,491 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, a7ec25f2-1303-4945-b62d-cdd672538442, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:39,495 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, a7ec25f2-1303-4945-b62d-cdd672538442, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:40,355 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:40,356 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-12-23 19:05:40,356 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:40,357 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 047c2307-c82c-4bf1-bce0-e46a27e776ea, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:40,358 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 047c2307-c82c-4bf1-bce0-e46a27e776ea, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:40,360 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 047c2307-c82c-4bf1-bce0-e46a27e776ea, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:47,460 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:47,461 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:47,462 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 5f36f126-a33f-45e1-9f1e-2856e4d5915b, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:47,463 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 5f36f126-a33f-45e1-9f1e-2856e4d5915b, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:47,466 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 5f36f126-a33f-45e1-9f1e-2856e4d5915b, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:48,336 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:48,337 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:48,337 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 89749a52-4b1c-432c-9c69-4e871a1f675e, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:48,344 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 89749a52-4b1c-432c-9c69-4e871a1f675e, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:48,346 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 89749a52-4b1c-432c-9c69-4e871a1f675e, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:49,806 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:49,807 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-12-23 19:05:49,807 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:49,808 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, bcca5ab0-a108-4855-acaa-a0fec06cb01e, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:49,809 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, bcca5ab0-a108-4855-acaa-a0fec06cb01e, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:49,815 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, bcca5ab0-a108-4855-acaa-a0fec06cb01e, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:55,554 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657981  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 19:05:55,554 - DEBUG - ydb.connection.1657981  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 19:05:55,595 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657981  - context_resolve: Resolving endpoints for database /Root
2025-12-23 19:05:55,596 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ListEndpoints, 065e81d6-aebf-4242-99a1-51da4f06d581, localhost:20617): creating call state
2025-12-23 19:05:55,596 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ListEndpoints, 065e81d6-aebf-4242-99a1-51da4f06d581, localhost:20617): request = { database: "/Root" }
2025-12-23 19:05:55,607 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ListEndpoints, 065e81d6-aebf-4242-99a1-51da4f06d581, localhost:20617): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ou4bsyjxme.auto.internal" port: 20617 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-23 19:05:55,608 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657981  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ou4bsyjxme.auto.internal:20617, location 1, ssl: False>]>
2025-12-23 19:05:55,609 - DEBUG - ydb.connection.1657981  - close: Closing channel for endpoint localhost:20617
2025-12-23 19:05:55,919 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:55,920 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:55,920 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 43fdbb07-8f87-43de-b8c5-957d345da63c, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:55,921 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 43fdbb07-8f87-43de-b8c5-957d345da63c, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:55,923 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 43fdbb07-8f87-43de-b8c5-957d345da63c, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:56,475 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:56,475 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:56,476 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 2e6f6bb4-688d-45fc-b6a4-09caec493a1b, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:56,477 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 2e6f6bb4-688d-45fc-b6a4-09caec493a1b, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:56,512 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 2e6f6bb4-688d-45fc-b6a4-09caec493a1b, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:57,591 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:57,592 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-12-23 19:05:57,592 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:05:57,593 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 13d788f8-2aa0-457b-ba81-b19b445ac2bc, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:05:57,594 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 13d788f8-2aa0-457b-ba81-b19b445ac2bc, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:57,596 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 13d788f8-2aa0-457b-ba81-b19b445ac2bc, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:03,953 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:03,954 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:03,955 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, c823bf41-b78d-4436-b833-b05ea905738c, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:03,956 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, c823bf41-b78d-4436-b833-b05ea905738c, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:03,960 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, c823bf41-b78d-4436-b833-b05ea905738c, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:04,554 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:04,555 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:04,556 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, f11cf8c7-5479-4593-802c-ffdc572229e1, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:04,557 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, f11cf8c7-5479-4593-802c-ffdc572229e1, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:04,559 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, f11cf8c7-5479-4593-802c-ffdc572229e1, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:05,570 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:05,571 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-12-23 19:06:05,572 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:05,573 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 88156ed9-0714-466d-975c-5518e1f504e2, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:05,573 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 88156ed9-0714-466d-975c-5518e1f504e2, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:05,575 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 88156ed9-0714-466d-975c-5518e1f504e2, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:07,354 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:07,355 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:07,355 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 0799210f-fb03-4493-b643-e9892da1a0db, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:07,356 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 0799210f-fb03-4493-b643-e9892da1a0db, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:07,378 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 0799210f-fb03-4493-b643-e9892da1a0db, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:08,047 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:08,048 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:08,049 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, d8e3fddb-b455-4d50-9b79-c290b7a43608, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:08,050 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, d8e3fddb-b455-4d50-9b79-c290b7a43608, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:08,052 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, d8e3fddb-b455-4d50-9b79-c290b7a43608, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:08,564 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:08,565 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:08,566 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, fabfaa44-adb1-470d-a8a2-cc0e8ffceb54, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:08,567 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, fabfaa44-adb1-470d-a8a2-cc0e8ffceb54, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:08,568 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, fabfaa44-adb1-470d-a8a2-cc0e8ffceb54, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:14,388 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:24,435 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:24,436 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 6a7a96d9-7cf3-49e3-92b5-2190a1d66603, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:24,437 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 6a7a96d9-7cf3-49e3-92b5-2190a1d66603, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:24,438 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 6a7a96d9-7cf3-49e3-92b5-2190a1d66603, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:28,970 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:29,005 - INFO - root.1657981  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1595704)
2025-12-23 19:06:29,006 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:29,007 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 9ca9605b-c0ba-4a57-a16a-e99f3427fe55, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:29,007 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 9ca9605b-c0ba-4a57-a16a-e99f3427fe55, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:29,009 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 9ca9605b-c0ba-4a57-a16a-e99f3427fe55, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:29,489 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:29,490 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:29,491 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, ffecfabd-331d-4b85-bc43-12536cc58361, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:29,492 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, ffecfabd-331d-4b85-bc43-12536cc58361, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:29,493 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, ffecfabd-331d-4b85-bc43-12536cc58361, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:34,681 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:44,767 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:44,768 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, c84e3d96-9cec-4098-b399-9c3c5a6df8e5, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:44,769 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, c84e3d96-9cec-4098-b399-9c3c5a6df8e5, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:44,770 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, c84e3d96-9cec-4098-b399-9c3c5a6df8e5, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:06:49,448 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:59,544 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:06:59,545 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 35a37d77-b7fb-4d63-9d94-a70c130e2fe3, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:06:59,546 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 35a37d77-b7fb-4d63-9d94-a70c130e2fe3, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:06:59,548 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 35a37d77-b7fb-4d63-9d94-a70c130e2fe3, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:02,174 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:12,190 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:12,191 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, f8611715-d3c1-476a-ade9-84a9fa3159b2, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:12,192 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, f8611715-d3c1-476a-ade9-84a9fa3159b2, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:07:12,194 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, f8611715-d3c1-476a-ade9-84a9fa3159b2, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:13,563 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:13,568 - INFO - root.1657981  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1497984)
2025-12-23 19:07:13,568 - INFO - root.1657981  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_16_compression` 8009216 / 1497984: 5.346663248739639
2025-12-23 19:07:13,570 - INFO - ya.test.1657981  - pytest_runtest_teardown: Test teardown
