2025-12-23 19:07:13,576 - INFO - ya.test.1657981  - pytest_runtest_setup: ####################################################################################################
2025-12-23 19:07:13,577 - INFO - ya.test.1657981  - pytest_runtest_setup: test_all_supported_compression[zstd_17_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 17]
2025-12-23 19:07:13,577 - INFO - ya.test.1657981  - pytest_runtest_setup: ####################################################################################################
2025-12-23 19:07:13,577 - INFO - ya.test.1657981  - pytest_runtest_setup: Test setup
2025-12-23 19:07:13,579 - INFO - ya.test.1657981  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_17_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 17])
2025-12-23 19:07:13,580 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:13,580 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, bec4190e-f75a-4b69-ac58-1a654634c026, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:13,581 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, bec4190e-f75a-4b69-ac58-1a654634c026, 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_17_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 17)\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:07:13,590 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, bec4190e-f75a-4b69-ac58-1a654634c026, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:17,906 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:17,906 - INFO - __tests__.alter_compression.1657981  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_17_compression created
2025-12-23 19:07:17,907 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:17,907 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 377a23bc-c594-4bae-9060-0c72e99905f2, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:17,908 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 377a23bc-c594-4bae-9060-0c72e99905f2, 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_17_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:07:17,909 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 377a23bc-c594-4bae-9060-0c72e99905f2, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:19,365 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:19,365 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:19,366 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 8488e02c-66aa-4006-a8e4-dcdafd10ed3f, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:19,367 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 8488e02c-66aa-4006-a8e4-dcdafd10ed3f, 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_17_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:07:19,368 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 8488e02c-66aa-4006-a8e4-dcdafd10ed3f, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:25,095 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:25,095 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:25,096 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 51da239b-2e68-4b63-afb0-2d417ce4a69f, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:25,097 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 51da239b-2e68-4b63-afb0-2d417ce4a69f, 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_17_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:07:25,102 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 51da239b-2e68-4b63-afb0-2d417ce4a69f, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:26,497 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:26,498 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:26,499 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, e507eced-4847-48b6-9566-8c92cbf00701, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:26,500 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, e507eced-4847-48b6-9566-8c92cbf00701, 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_17_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:07:26,502 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, e507eced-4847-48b6-9566-8c92cbf00701, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:28,635 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:28,636 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-12-23 19:07:28,637 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:28,637 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 817596ef-d5ad-42fa-8607-5aea72ea68ef, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:28,639 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 817596ef-d5ad-42fa-8607-5aea72ea68ef, 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_17_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:07:28,640 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 817596ef-d5ad-42fa-8607-5aea72ea68ef, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:34,451 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:34,452 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:34,452 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 4ce1394e-75f7-432e-91de-978399d463f9, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:34,453 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 4ce1394e-75f7-432e-91de-978399d463f9, 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_17_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:07:34,461 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 4ce1394e-75f7-432e-91de-978399d463f9, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:34,796 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:34,797 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:34,797 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 5a3d7f5d-3ab1-46fd-a934-e36f7c0b9d66, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:34,798 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 5a3d7f5d-3ab1-46fd-a934-e36f7c0b9d66, 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_17_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:07:34,800 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 5a3d7f5d-3ab1-46fd-a934-e36f7c0b9d66, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:35,286 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:35,287 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-12-23 19:07:35,288 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:35,288 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, cc05281b-d1cc-4f17-9ddd-826ffaa100c3, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:35,289 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, cc05281b-d1cc-4f17-9ddd-826ffaa100c3, 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_17_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:07:35,291 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, cc05281b-d1cc-4f17-9ddd-826ffaa100c3, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:39,631 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:39,631 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:39,632 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 95554e48-5767-4a10-b338-7795f175ac1d, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:39,633 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 95554e48-5767-4a10-b338-7795f175ac1d, 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_17_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:07:39,635 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 95554e48-5767-4a10-b338-7795f175ac1d, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:39,939 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:39,940 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:39,941 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 0f16ac60-0d4a-4a92-9709-b7fbbb0a4123, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:39,942 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 0f16ac60-0d4a-4a92-9709-b7fbbb0a4123, 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_17_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:07:39,943 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 0f16ac60-0d4a-4a92-9709-b7fbbb0a4123, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:40,359 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:40,360 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-12-23 19:07:40,360 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:40,361 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 39741a28-4038-4ea9-83af-cb6f6ba86f8f, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:40,362 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 39741a28-4038-4ea9-83af-cb6f6ba86f8f, 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_17_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:07:40,363 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 39741a28-4038-4ea9-83af-cb6f6ba86f8f, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:44,451 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:44,451 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:44,452 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, cbe9da6f-9f42-49b2-b60f-4310240d1114, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:44,453 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, cbe9da6f-9f42-49b2-b60f-4310240d1114, 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_17_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:07:44,454 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, cbe9da6f-9f42-49b2-b60f-4310240d1114, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:44,727 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:44,728 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:44,729 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 0ef95956-fdb3-4825-80ac-d024127a8ca1, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:44,730 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 0ef95956-fdb3-4825-80ac-d024127a8ca1, 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_17_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:07:44,731 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 0ef95956-fdb3-4825-80ac-d024127a8ca1, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:45,139 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:45,140 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-12-23 19:07:45,140 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:45,141 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, afcbc067-d0f7-419e-a9b4-120c2b6157f5, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:45,142 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, afcbc067-d0f7-419e-a9b4-120c2b6157f5, 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_17_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:07:45,144 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, afcbc067-d0f7-419e-a9b4-120c2b6157f5, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:49,558 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657981  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 19:07:49,559 - 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:07:49,584 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657981  - context_resolve: Resolving endpoints for database /Root
2025-12-23 19:07:49,585 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ListEndpoints, 2b068ddc-7dcf-4b1c-bf8d-7c326e28569b, localhost:20617): creating call state
2025-12-23 19:07:49,585 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ListEndpoints, 2b068ddc-7dcf-4b1c-bf8d-7c326e28569b, localhost:20617): request = { database: "/Root" }
2025-12-23 19:07:49,596 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ListEndpoints, 2b068ddc-7dcf-4b1c-bf8d-7c326e28569b, 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:07:49,597 - 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:07:49,598 - DEBUG - ydb.connection.1657981  - close: Closing channel for endpoint localhost:20617
2025-12-23 19:07:49,948 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:49,948 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:49,949 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 011b3877-2840-4f09-8e05-2554d0c38ade, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:49,950 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 011b3877-2840-4f09-8e05-2554d0c38ade, 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_17_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:07:49,951 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 011b3877-2840-4f09-8e05-2554d0c38ade, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:50,306 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:50,307 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:50,307 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, fbb79e65-0f6e-4b18-997c-d943a06a7937, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:50,308 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, fbb79e65-0f6e-4b18-997c-d943a06a7937, 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_17_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:07:50,309 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, fbb79e65-0f6e-4b18-997c-d943a06a7937, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:50,923 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:50,924 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-12-23 19:07:50,924 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:50,925 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 3c78121c-f1a6-4ece-8371-4005dff00fca, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:50,926 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 3c78121c-f1a6-4ece-8371-4005dff00fca, 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_17_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:07:50,931 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 3c78121c-f1a6-4ece-8371-4005dff00fca, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:56,547 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:56,548 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:56,548 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 1bfabd88-74bd-40fd-8df4-fe9114af839a, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:56,549 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 1bfabd88-74bd-40fd-8df4-fe9114af839a, 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_17_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:07:56,551 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 1bfabd88-74bd-40fd-8df4-fe9114af839a, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:56,819 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:56,820 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:56,821 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 1a36edd1-6532-4000-a83e-0e27a98815ef, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:56,822 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 1a36edd1-6532-4000-a83e-0e27a98815ef, 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_17_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:07:56,823 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 1a36edd1-6532-4000-a83e-0e27a98815ef, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:07:57,391 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:57,392 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-12-23 19:07:57,393 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:07:57,393 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, b794d89b-07e2-4f55-a968-bd7a09ef21e5, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:07:57,394 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, b794d89b-07e2-4f55-a968-bd7a09ef21e5, 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_17_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:07:57,395 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, b794d89b-07e2-4f55-a968-bd7a09ef21e5, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:02,810 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:02,811 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:02,812 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 92aaee81-fd21-4352-b870-82614f28c130, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:02,813 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 92aaee81-fd21-4352-b870-82614f28c130, 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_17_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:08:02,820 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 92aaee81-fd21-4352-b870-82614f28c130, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:03,252 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:03,253 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:03,254 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 426feb6a-e71a-4d76-b836-b90f7cfd8ea8, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:03,255 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 426feb6a-e71a-4d76-b836-b90f7cfd8ea8, 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_17_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:08:03,257 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 426feb6a-e71a-4d76-b836-b90f7cfd8ea8, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:04,101 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:04,102 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-12-23 19:08:04,103 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:04,103 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, c3ea9bf6-8c90-423e-8dca-a82fc3c407fc, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:04,104 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, c3ea9bf6-8c90-423e-8dca-a82fc3c407fc, 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_17_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:08:04,107 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, c3ea9bf6-8c90-423e-8dca-a82fc3c407fc, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:09,507 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:09,507 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:09,508 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 99af12ba-5a8a-4657-9fa9-75ca965764bb, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:09,509 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 99af12ba-5a8a-4657-9fa9-75ca965764bb, 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_17_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:08:09,511 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 99af12ba-5a8a-4657-9fa9-75ca965764bb, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:09,966 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:09,967 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:09,968 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 69316c7a-ad97-4b43-8f66-ae10bf318ba2, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:09,968 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 69316c7a-ad97-4b43-8f66-ae10bf318ba2, 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_17_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:08:09,973 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 69316c7a-ad97-4b43-8f66-ae10bf318ba2, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:10,671 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:10,672 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-12-23 19:08:10,672 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:10,673 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, a46dba8d-c107-4b40-9c18-72952c985fd5, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:10,674 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, a46dba8d-c107-4b40-9c18-72952c985fd5, 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_17_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:08:10,675 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, a46dba8d-c107-4b40-9c18-72952c985fd5, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:16,003 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:16,004 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:16,004 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 72322f31-cc7e-48bf-a281-205189af0848, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:16,005 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 72322f31-cc7e-48bf-a281-205189af0848, 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_17_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:08:16,017 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 72322f31-cc7e-48bf-a281-205189af0848, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:16,351 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:16,351 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:16,352 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 21be2498-c02c-4286-8943-6fb885a285af, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:16,353 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 21be2498-c02c-4286-8943-6fb885a285af, 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_17_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:08:16,354 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 21be2498-c02c-4286-8943-6fb885a285af, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:17,216 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:17,217 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-12-23 19:08:17,217 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:17,218 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 7e8ce3d5-2f2e-4e70-887d-fe08ae26e9ea, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:17,219 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 7e8ce3d5-2f2e-4e70-887d-fe08ae26e9ea, 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_17_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:08:17,220 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 7e8ce3d5-2f2e-4e70-887d-fe08ae26e9ea, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:22,283 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:22,284 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:22,285 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, cb468cf0-3c8a-4d1a-ae73-42ed8dfe0051, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:22,285 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, cb468cf0-3c8a-4d1a-ae73-42ed8dfe0051, 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_17_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:08:22,287 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, cb468cf0-3c8a-4d1a-ae73-42ed8dfe0051, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:22,564 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:22,565 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:22,566 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 80ab94c9-bd3a-416b-866f-8cfd033b28af, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:22,567 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 80ab94c9-bd3a-416b-866f-8cfd033b28af, 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_17_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:08:22,569 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 80ab94c9-bd3a-416b-866f-8cfd033b28af, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:23,339 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:23,340 - INFO - __tests__.alter_compression.1657981  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_17_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-12-23 19:08:23,340 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:23,341 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 3eb64f91-3447-44a0-9a99-b5818aadc98b, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:23,342 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 3eb64f91-3447-44a0-9a99-b5818aadc98b, 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_17_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:08:23,344 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 3eb64f91-3447-44a0-9a99-b5818aadc98b, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:25,081 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:25,081 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:25,082 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, d471443d-2edc-4e54-bd5e-95ab9d224eb4, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:25,083 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, d471443d-2edc-4e54-bd5e-95ab9d224eb4, 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_17_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:08:25,085 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, d471443d-2edc-4e54-bd5e-95ab9d224eb4, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:25,527 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:25,528 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:25,529 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, c5f8a106-72d0-4d2c-8562-a33abbd51ab4, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:25,530 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, c5f8a106-72d0-4d2c-8562-a33abbd51ab4, 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_17_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:08:25,532 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, c5f8a106-72d0-4d2c-8562-a33abbd51ab4, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:26,182 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:26,183 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:26,184 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, c0ed6e4f-2569-4094-8628-48e1d9995920, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:26,185 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, c0ed6e4f-2569-4094-8628-48e1d9995920, 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_17_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:08:26,188 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, c0ed6e4f-2569-4094-8628-48e1d9995920, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:30,625 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:40,665 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:40,666 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, b9e39cac-b003-407a-995b-a6a83d18d9fd, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:40,667 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, b9e39cac-b003-407a-995b-a6a83d18d9fd, 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_17_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:08:40,668 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, b9e39cac-b003-407a-995b-a6a83d18d9fd, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:44,975 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:45,024 - INFO - root.1657981  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_17_compression`, volumes `value` (8000000, 1595704)
2025-12-23 19:08:45,025 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:45,026 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, a978399c-f352-47cc-9f7a-ce79e6b40963, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:45,027 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, a978399c-f352-47cc-9f7a-ce79e6b40963, 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_17_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:08:45,028 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, a978399c-f352-47cc-9f7a-ce79e6b40963, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:45,652 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:45,653 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:45,654 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 7640a642-9669-483c-8c15-fc40c0c7c58a, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:45,655 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 7640a642-9669-483c-8c15-fc40c0c7c58a, 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_17_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:08:45,656 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 7640a642-9669-483c-8c15-fc40c0c7c58a, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:08:49,450 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:59,498 - DEBUG - ydb.query.pool.1657981  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:08:59,499 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(ExecuteQuery, 0892a76d-6de3-4ed5-aef4-7fed4ff8b10b, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:08:59,500 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(ExecuteQuery, 0892a76d-6de3-4ed5-aef4-7fed4ff8b10b, 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_17_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:08:59,501 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(ExecuteQuery, 0892a76d-6de3-4ed5-aef4-7fed4ff8b10b, ghrun-ou4bsyjxme.auto.internal:20617): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:09:02,954 - DEBUG - ydb.query.pool.1657981  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=
2025-12-23 19:09:03,049 - INFO - root.1657981  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_17_compression`, volumes `value` (8000000, 1595704)
2025-12-23 19:09:03,050 - INFO - root.1657981  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_17_compression` 8009216 / 1595704: 5.019236650406341
2025-12-23 19:09:03,051 - INFO - ya.test.1657981  - pytest_runtest_teardown: Test teardown
2025-12-23 19:09:03,052 - DEBUG - ydb.connection.1657981  - _prepare_call: RpcState(DeleteSession, 5d26f2f5-69ce-4dc0-bc34-0ed474caebb9, ghrun-ou4bsyjxme.auto.internal:20617): creating call state
2025-12-23 19:09:03,053 - DEBUG - ydb.connection.1657981  - _log_request: RpcState(DeleteSession, 5d26f2f5-69ce-4dc0-bc34-0ed474caebb9, ghrun-ou4bsyjxme.auto.internal:20617): request = { session_id: "ydb://session/3?node_id=1&id=ZDlmOTU1OTMtYWVhNGQyODAtYmZmMGJmMTYtNWU3N2NhZDU=" }
2025-12-23 19:09:03,063 - DEBUG - ydb.connection.1657981  - _log_response: RpcState(DeleteSession, 5d26f2f5-69ce-4dc0-bc34-0ed474caebb9, ghrun-ou4bsyjxme.auto.internal:20617): response = { status: SUCCESS }
2025-12-23 19:09:03,064 - DEBUG - ydb.query.pool.1657981  - stop: All session were deleted.
2025-12-23 19:09:03,066 - DEBUG - ydb.topic.1657981  - close: Close topic client
2025-12-23 19:09:03,066 - DEBUG - ydb.topic.1657981  - close: Topic client was closed
2025-12-23 19:09:03,067 - DEBUG - ydb.connection.1657981  - close: Closing channel for endpoint localhost:20617
2025-12-23 19:09:03,074 - DEBUG - ydb.connection.1657981  - close: Closing channel for endpoint ghrun-ou4bsyjxme.auto.internal:20617
2025-12-23 19:09:03,076 - INFO - ydb.pool.Discovery.1657981  - run: Successfully terminated discovery process
2025-12-23 19:09:15,082 - INFO - ydb.tests.library.harness.kikimr_runner.1657981  - stop: Stopped node localhost:20617/1
2025-12-23 19:09:15,086 - DEBUG - library.python.filelock.1657981  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4901
2025-12-23 19:09:15,087 - DEBUG - library.python.filelock.1657981  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27851
2025-12-23 19:09:15,088 - DEBUG - library.python.filelock.1657981  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21776
2025-12-23 19:09:15,088 - DEBUG - library.python.filelock.1657981  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20617
2025-12-23 19:09:15,088 - DEBUG - library.python.filelock.1657981  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28369
