2025-12-05 16:21:04,616 - INFO - ya.test.23023  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:21:04,636 - INFO - ya.test.23023  - pytest_runtest_setup: test_all_supported_compression[zstd_7_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 7]
2025-12-05 16:21:04,643 - INFO - ya.test.23023  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:21:04,654 - INFO - ya.test.23023  - pytest_runtest_setup: Test setup
2025-12-05 16:21:04,656 - INFO - ya.test.23023  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_7_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 7])
2025-12-05 16:21:04,656 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:04,657 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, e8051557-adb5-40dd-8885-75048a86e735, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:04,658 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, e8051557-adb5-40dd-8885-75048a86e735, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_7_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 7)\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-05 16:21:04,659 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, e8051557-adb5-40dd-8885-75048a86e735, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:07,738 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:07,742 - INFO - __tests__.alter_compression.23023  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_7_compression created
2025-12-05 16:21:07,742 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:07,743 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 33e2fd7d-792a-4a87-95f5-3808806bc239, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:07,744 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 33e2fd7d-792a-4a87-95f5-3808806bc239, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_7_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:07,745 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 33e2fd7d-792a-4a87-95f5-3808806bc239, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:08,662 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:08,663 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:08,664 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 5c27d76d-417b-4894-81ab-37f401f647e0, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:08,665 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 5c27d76d-417b-4894-81ab-37f401f647e0, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:08,667 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 5c27d76d-417b-4894-81ab-37f401f647e0, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:09,798 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:09,798 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:09,799 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, d8c80e78-68ca-4369-b5a8-99bcf075639d, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:09,799 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, d8c80e78-68ca-4369-b5a8-99bcf075639d, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:09,801 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, d8c80e78-68ca-4369-b5a8-99bcf075639d, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:10,628 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:10,629 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:10,630 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 223edde4-3a28-4a5f-be4e-eaa8851e3142, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:10,630 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 223edde4-3a28-4a5f-be4e-eaa8851e3142, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:10,631 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 223edde4-3a28-4a5f-be4e-eaa8851e3142, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:12,230 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:12,238 - INFO - __tests__.alter_compression.23023  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-12-05 16:21:12,243 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:12,244 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 5a5f129a-c101-427c-8a65-eeb0ac23c298, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:12,244 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 5a5f129a-c101-427c-8a65-eeb0ac23c298, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:12,245 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 5a5f129a-c101-427c-8a65-eeb0ac23c298, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:13,424 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:13,428 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:13,429 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 9983243b-3519-438f-b402-93687e67e197, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:13,430 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 9983243b-3519-438f-b402-93687e67e197, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:13,431 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 9983243b-3519-438f-b402-93687e67e197, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:13,678 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:13,679 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:13,680 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, f6e98ef5-5dcd-4a05-9fcf-b658dae2c1d7, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:13,681 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, f6e98ef5-5dcd-4a05-9fcf-b658dae2c1d7, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:13,682 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, f6e98ef5-5dcd-4a05-9fcf-b658dae2c1d7, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:13,940 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:13,969 - INFO - __tests__.alter_compression.23023  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-12-05 16:21:13,978 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:13,988 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 72c59047-3fbe-4651-823f-ff89e1016063, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:13,998 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 72c59047-3fbe-4651-823f-ff89e1016063, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:14,010 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 72c59047-3fbe-4651-823f-ff89e1016063, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:15,090 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:15,092 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:15,092 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, b7a6b11e-644b-4a7b-aefe-951f2581e06d, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:15,093 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, b7a6b11e-644b-4a7b-aefe-951f2581e06d, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:15,095 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, b7a6b11e-644b-4a7b-aefe-951f2581e06d, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:15,284 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:15,297 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:15,297 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, f55c1a85-cf10-4644-a547-90bd1848c0ed, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:15,298 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, f55c1a85-cf10-4644-a547-90bd1848c0ed, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:15,300 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, f55c1a85-cf10-4644-a547-90bd1848c0ed, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:15,626 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:15,674 - INFO - __tests__.alter_compression.23023  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956352, 'BlobCount': 384}}
2025-12-05 16:21:15,675 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:15,676 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, e7cb37ee-c4f6-4339-b07d-25dd1cde4630, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:15,688 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, e7cb37ee-c4f6-4339-b07d-25dd1cde4630, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:15,757 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, e7cb37ee-c4f6-4339-b07d-25dd1cde4630, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:16,945 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:16,950 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:16,951 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, ed57a647-8c95-409b-af89-e5eb4e3bf492, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:16,952 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, ed57a647-8c95-409b-af89-e5eb4e3bf492, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:16,953 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, ed57a647-8c95-409b-af89-e5eb4e3bf492, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:17,152 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:17,153 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:17,154 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 2feb0db0-f5cb-42ab-9354-0918a6a228e8, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:17,154 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 2feb0db0-f5cb-42ab-9354-0918a6a228e8, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:17,159 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 2feb0db0-f5cb-42ab-9354-0918a6a228e8, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:17,514 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:17,516 - INFO - __tests__.alter_compression.23023  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275712, 'BlobCount': 512}}
2025-12-05 16:21:17,517 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:17,517 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 3e139437-0ac7-4f42-9812-0e9bfa489f3c, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:17,518 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 3e139437-0ac7-4f42-9812-0e9bfa489f3c, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:17,520 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 3e139437-0ac7-4f42-9812-0e9bfa489f3c, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:18,614 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:18,615 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:18,616 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 7eda0d2b-1a4e-4f9a-803f-6aa5909b7fef, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:18,617 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 7eda0d2b-1a4e-4f9a-803f-6aa5909b7fef, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:18,618 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 7eda0d2b-1a4e-4f9a-803f-6aa5909b7fef, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:18,835 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:18,837 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:18,838 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, b0f619fb-89c4-4bd4-a1a6-cfb13d4a127e, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:18,839 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, b0f619fb-89c4-4bd4-a1a6-cfb13d4a127e, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:18,840 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, b0f619fb-89c4-4bd4-a1a6-cfb13d4a127e, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:19,225 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:19,226 - INFO - __tests__.alter_compression.23023  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594672, 'BlobCount': 640}}
2025-12-05 16:21:19,226 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:19,227 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 7ddc61fd-10f2-4663-8d12-1993e8327754, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:19,228 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 7ddc61fd-10f2-4663-8d12-1993e8327754, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:19,235 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 7ddc61fd-10f2-4663-8d12-1993e8327754, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:20,196 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:20,197 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:20,197 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 307e93b3-a5f1-4db1-8e78-16e474644c0b, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:20,198 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 307e93b3-a5f1-4db1-8e78-16e474644c0b, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:20,199 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 307e93b3-a5f1-4db1-8e78-16e474644c0b, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:20,395 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:20,396 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:20,397 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 7e76eb6a-03ab-4934-bcb6-d2e6914e9d80, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:20,398 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 7e76eb6a-03ab-4934-bcb6-d2e6914e9d80, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:20,399 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 7e76eb6a-03ab-4934-bcb6-d2e6914e9d80, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:20,855 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:20,856 - INFO - __tests__.alter_compression.23023  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913856, 'BlobCount': 768}}
2025-12-05 16:21:20,856 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:20,857 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 2c153ed7-b106-4521-b29c-b0be502a017c, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:20,857 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 2c153ed7-b106-4521-b29c-b0be502a017c, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:20,859 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 2c153ed7-b106-4521-b29c-b0be502a017c, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:21,868 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:21,869 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:21,870 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 360cb263-5dca-4fd8-aa27-e5eb478f92f0, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:21,871 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 360cb263-5dca-4fd8-aa27-e5eb478f92f0, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:21,872 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 360cb263-5dca-4fd8-aa27-e5eb478f92f0, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:22,067 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:22,068 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:22,069 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 28019b91-804a-493f-b4d4-7c28521b59fd, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:22,070 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 28019b91-804a-493f-b4d4-7c28521b59fd, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:22,071 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 28019b91-804a-493f-b4d4-7c28521b59fd, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:22,545 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:22,546 - INFO - __tests__.alter_compression.23023  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2232992, 'BlobCount': 896}}
2025-12-05 16:21:22,546 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:22,570 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 4a270113-5f5b-4490-a8f3-70b87d755c99, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:22,571 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 4a270113-5f5b-4490-a8f3-70b87d755c99, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:22,572 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 4a270113-5f5b-4490-a8f3-70b87d755c99, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:23,669 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:23,670 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:23,671 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 724c0eab-f89b-40f9-a5d5-c507e0136b6b, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:23,671 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 724c0eab-f89b-40f9-a5d5-c507e0136b6b, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:23,672 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 724c0eab-f89b-40f9-a5d5-c507e0136b6b, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:23,879 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:23,905 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:23,907 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, d4deab48-5094-435c-88ff-0bb7d8b2312d, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:23,908 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, d4deab48-5094-435c-88ff-0bb7d8b2312d, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:23,910 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, d4deab48-5094-435c-88ff-0bb7d8b2312d, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:24,475 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:24,496 - INFO - __tests__.alter_compression.23023  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552480, 'BlobCount': 1024}}
2025-12-05 16:21:24,496 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:24,497 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 482eaeba-a16c-4209-b034-381712e8375c, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:24,498 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 482eaeba-a16c-4209-b034-381712e8375c, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:24,500 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 482eaeba-a16c-4209-b034-381712e8375c, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:25,384 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:25,385 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:25,386 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, d7ea2e67-64d2-4cd8-90a7-897377d575a9, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:25,387 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, d7ea2e67-64d2-4cd8-90a7-897377d575a9, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:25,388 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, d7ea2e67-64d2-4cd8-90a7-897377d575a9, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:25,577 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:25,578 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:25,584 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 125270a6-fa63-4c24-8332-9419a5ca4beb, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:25,589 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 125270a6-fa63-4c24-8332-9419a5ca4beb, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:25,591 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 125270a6-fa63-4c24-8332-9419a5ca4beb, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:26,164 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:26,173 - INFO - __tests__.alter_compression.23023  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871488, 'BlobCount': 1152}}
2025-12-05 16:21:26,187 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:26,205 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 14b965ec-409e-4bad-b33f-89f246a03e44, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:26,206 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 14b965ec-409e-4bad-b33f-89f246a03e44, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:26,208 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 14b965ec-409e-4bad-b33f-89f246a03e44, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:27,184 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:27,208 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:27,209 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 89f7e648-8ee5-441e-94f8-578736cb4c0f, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:27,210 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 89f7e648-8ee5-441e-94f8-578736cb4c0f, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:27,211 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 89f7e648-8ee5-441e-94f8-578736cb4c0f, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:27,462 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:27,463 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:27,464 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 9e884766-1144-4f29-bbe1-bd753331fb8e, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:27,464 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 9e884766-1144-4f29-bbe1-bd753331fb8e, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:27,465 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 9e884766-1144-4f29-bbe1-bd753331fb8e, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:28,061 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:28,064 - INFO - __tests__.alter_compression.23023  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3190992, 'BlobCount': 1280}}
2025-12-05 16:21:28,064 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:28,065 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 4ebb5d91-026e-4f91-b303-d351e0c5efcf, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:28,065 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 4ebb5d91-026e-4f91-b303-d351e0c5efcf, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_7_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:28,066 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 4ebb5d91-026e-4f91-b303-d351e0c5efcf, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:28,879 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:28,879 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:28,880 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, e270f3eb-a067-49fe-8b22-d7ee7054fc3a, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:28,881 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, e270f3eb-a067-49fe-8b22-d7ee7054fc3a, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:28,881 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, e270f3eb-a067-49fe-8b22-d7ee7054fc3a, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:29,168 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:29,170 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:29,171 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 67e02d2f-ffc2-434f-97d9-792300a527cd, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:29,171 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 67e02d2f-ffc2-434f-97d9-792300a527cd, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:29,173 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 67e02d2f-ffc2-434f-97d9-792300a527cd, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:29,375 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:29,376 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:29,376 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 36cc9fd3-68a3-4292-a07a-45c8082335b0, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:29,377 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 36cc9fd3-68a3-4292-a07a-45c8082335b0, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_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-05 16:21:29,378 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 36cc9fd3-68a3-4292-a07a-45c8082335b0, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:32,338 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:42,376 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:42,380 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 7b7959d8-9cf4-456f-87a5-5c4c82853515, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:42,381 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 7b7959d8-9cf4-456f-87a5-5c4c82853515, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_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-05 16:21:42,383 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 7b7959d8-9cf4-456f-87a5-5c4c82853515, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:45,580 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:45,622 - INFO - root.23023  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_7_compression`, volumes `value` (8000000, 1595496)
2025-12-05 16:21:45,629 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:45,630 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, ebb4e424-c87e-46f6-8091-f545de1cccb2, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:45,631 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, ebb4e424-c87e-46f6-8091-f545de1cccb2, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" 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_7_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-05 16:21:45,632 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, ebb4e424-c87e-46f6-8091-f545de1cccb2, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:45,934 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:45,935 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:21:45,936 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 3fdf8425-dcfa-4b29-b2a1-6cf6dc3adc77, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:21:45,937 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 3fdf8425-dcfa-4b29-b2a1-6cf6dc3adc77, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_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-05 16:21:45,938 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 3fdf8425-dcfa-4b29-b2a1-6cf6dc3adc77, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:50,132 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:22:00,170 - DEBUG - ydb.query.pool.23023  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:22:00,171 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ExecuteQuery, 89bb882a-97f5-44a8-91c5-57cd996e1ce9, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:22:00,186 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ExecuteQuery, 89bb882a-97f5-44a8-91c5-57cd996e1ce9, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_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-05 16:22:00,187 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ExecuteQuery, 89bb882a-97f5-44a8-91c5-57cd996e1ce9, ghrun-a66hhqwkt4.auto.internal:22687): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:00,413 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23023  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:22:00,413 - DEBUG - ydb.connection.23023  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 16:22:00,447 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23023  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:22:00,448 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(ListEndpoints, 81c7f990-eac4-40bc-8811-11c425d31839, localhost:22687): creating call state
2025-12-05 16:22:00,448 - DEBUG - ydb.connection.23023  - _log_request: RpcState(ListEndpoints, 81c7f990-eac4-40bc-8811-11c425d31839, localhost:22687): request = { database: "/Root" }
2025-12-05 16:22:00,496 - DEBUG - ydb.connection.23023  - _log_response: RpcState(ListEndpoints, 81c7f990-eac4-40bc-8811-11c425d31839, localhost:22687): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 22687 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-05 16:22:00,497 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23023  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:22687, location 1, ssl: False>]>
2025-12-05 16:22:00,498 - DEBUG - ydb.connection.23023  - close: Closing channel for endpoint localhost:22687
2025-12-05 16:22:03,847 - DEBUG - ydb.query.pool.23023  - release: Session returned to queue: ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=
2025-12-05 16:22:03,897 - INFO - root.23023  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_7_compression`, volumes `value` (8000000, 1595496)
2025-12-05 16:22:03,898 - INFO - root.23023  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_7_compression` 8092160 / 1595496: 5.071877334697173
2025-12-05 16:22:03,900 - INFO - ya.test.23023  - pytest_runtest_teardown: Test teardown
2025-12-05 16:22:03,901 - DEBUG - ydb.connection.23023  - _prepare_call: RpcState(DeleteSession, 9ddab00d-e65a-4f16-94a8-610f49f4ff35, ghrun-a66hhqwkt4.auto.internal:22687): creating call state
2025-12-05 16:22:03,902 - DEBUG - ydb.connection.23023  - _log_request: RpcState(DeleteSession, 9ddab00d-e65a-4f16-94a8-610f49f4ff35, ghrun-a66hhqwkt4.auto.internal:22687): request = { session_id: "ydb://session/3?node_id=1&id=NTZmNmZmNDYtMjBmNjBiNzUtM2MzYjZjMTktMjBiNGI2Nzg=" }
2025-12-05 16:22:03,907 - DEBUG - ydb.connection.23023  - _log_response: RpcState(DeleteSession, 9ddab00d-e65a-4f16-94a8-610f49f4ff35, ghrun-a66hhqwkt4.auto.internal:22687): response = { status: SUCCESS }
2025-12-05 16:22:03,908 - DEBUG - ydb.query.pool.23023  - stop: All session were deleted.
2025-12-05 16:22:03,909 - DEBUG - ydb.topic.23023  - close: Close topic client
2025-12-05 16:22:03,909 - DEBUG - ydb.topic.23023  - close: Topic client was closed
2025-12-05 16:22:03,910 - DEBUG - ydb.connection.23023  - close: Closing channel for endpoint localhost:22687
2025-12-05 16:22:03,915 - DEBUG - ydb.connection.23023  - close: Closing channel for endpoint ghrun-a66hhqwkt4.auto.internal:22687
2025-12-05 16:22:03,917 - INFO - ydb.pool.Discovery.23023  - run: Successfully terminated discovery process
2025-12-05 16:22:03,921 - DEBUG - ydb.connection.23023  - _rpc_error_handler: RpcState(AttachSession, 51098a3a-8b82-4142-93fa-3eef6631e89e, ghrun-a66hhqwkt4.auto.internal:22687): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-05 16:22:20,930 - INFO - ydb.tests.library.harness.kikimr_runner.23023  - stop: Stopped node localhost:22687/1
2025-12-05 16:22:20,931 - DEBUG - library.python.filelock.23023  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22738
2025-12-05 16:22:20,932 - DEBUG - library.python.filelock.23023  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11188
2025-12-05 16:22:20,932 - DEBUG - library.python.filelock.23023  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3671
2025-12-05 16:22:20,933 - DEBUG - library.python.filelock.23023  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22687
2025-12-05 16:22:20,933 - DEBUG - library.python.filelock.23023  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26572
