2025-12-05 16:21:52,043 - INFO - ya.test.23341  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:21:52,056 - INFO - ya.test.23341  - pytest_runtest_setup: test_all_supported_compression[zstd_21_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 21]
2025-12-05 16:21:52,065 - INFO - ya.test.23341  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:21:52,066 - INFO - ya.test.23341  - pytest_runtest_setup: Test setup
2025-12-05 16:21:52,073 - INFO - ya.test.23341  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_21_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 21])
2025-12-05 16:21:52,082 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:52,088 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 4c6fb939-325a-46e2-be35-15bf4a8b4ee9, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:52,089 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 4c6fb939-325a-46e2-be35-15bf4a8b4ee9, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_21_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 21)\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:52,091 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 4c6fb939-325a-46e2-be35-15bf4a8b4ee9, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:55,269 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:55,270 - INFO - __tests__.alter_compression.23341  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_21_compression created
2025-12-05 16:21:55,270 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:55,271 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, fd0913e5-c11e-446d-ad4b-eed50ee1b18d, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:55,272 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, fd0913e5-c11e-446d-ad4b-eed50ee1b18d, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_21_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:55,274 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, fd0913e5-c11e-446d-ad4b-eed50ee1b18d, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:56,298 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:56,298 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:56,298 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 3e68b3b7-a7c6-4050-b195-df37212ff2f1, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:56,299 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 3e68b3b7-a7c6-4050-b195-df37212ff2f1, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:56,300 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 3e68b3b7-a7c6-4050-b195-df37212ff2f1, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:01,223 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:01,224 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:01,225 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 842036b6-020d-4910-805e-8809d674bff6, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:01,226 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 842036b6-020d-4910-805e-8809d674bff6, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:01,227 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 842036b6-020d-4910-805e-8809d674bff6, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:02,268 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:02,306 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:02,307 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 19d0c574-9120-4bce-b98c-15d50531da2f, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:02,308 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 19d0c574-9120-4bce-b98c-15d50531da2f, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:02,310 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 19d0c574-9120-4bce-b98c-15d50531da2f, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:04,100 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:04,149 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-12-05 16:22:04,152 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:04,165 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 365617a7-48c5-48c6-bd45-504e09f85f60, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:04,166 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 365617a7-48c5-48c6-bd45-504e09f85f60, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:04,167 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 365617a7-48c5-48c6-bd45-504e09f85f60, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:08,343 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:08,343 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:08,344 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 0372219b-79c1-48a0-bfba-1f7617d9f86c, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:08,345 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 0372219b-79c1-48a0-bfba-1f7617d9f86c, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:08,346 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 0372219b-79c1-48a0-bfba-1f7617d9f86c, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:08,553 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:08,563 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:08,571 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 101c6055-593d-467a-8059-f270d098ac61, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:08,572 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 101c6055-593d-467a-8059-f270d098ac61, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:08,573 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 101c6055-593d-467a-8059-f270d098ac61, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:08,853 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:08,854 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-12-05 16:22:08,854 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:08,855 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, d50c108f-eafc-4d1e-90e9-57844fb947a9, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:08,856 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, d50c108f-eafc-4d1e-90e9-57844fb947a9, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:08,858 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, d50c108f-eafc-4d1e-90e9-57844fb947a9, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:12,261 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:12,262 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:12,262 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 8cbe7797-5e38-412e-aecb-456e48185ac9, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:12,263 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 8cbe7797-5e38-412e-aecb-456e48185ac9, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:12,264 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 8cbe7797-5e38-412e-aecb-456e48185ac9, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:12,468 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:12,473 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:12,474 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 508eecf5-7913-4cf1-8c68-67b996acd07c, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:12,474 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 508eecf5-7913-4cf1-8c68-67b996acd07c, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:12,476 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 508eecf5-7913-4cf1-8c68-67b996acd07c, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:12,784 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:12,784 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-12-05 16:22:12,796 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:12,797 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, d05f95c8-4c8e-41a0-83bd-5f648000b28f, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:12,798 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, d05f95c8-4c8e-41a0-83bd-5f648000b28f, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:12,799 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, d05f95c8-4c8e-41a0-83bd-5f648000b28f, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:15,594 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:15,595 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:15,596 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, d3733496-516b-460f-921d-491e9ee2e5f9, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:15,596 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, d3733496-516b-460f-921d-491e9ee2e5f9, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:15,598 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, d3733496-516b-460f-921d-491e9ee2e5f9, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:15,797 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:15,798 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:15,799 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 69e8f59b-23ed-4cc5-8e5f-a982c6f573ab, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:15,799 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 69e8f59b-23ed-4cc5-8e5f-a982c6f573ab, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:15,800 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 69e8f59b-23ed-4cc5-8e5f-a982c6f573ab, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:16,161 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:16,161 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-12-05 16:22:16,162 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:16,162 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 69ec8e82-c4a9-4845-8846-c1e5a052fba2, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:16,163 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 69ec8e82-c4a9-4845-8846-c1e5a052fba2, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:16,172 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 69ec8e82-c4a9-4845-8846-c1e5a052fba2, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:18,785 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:18,786 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:18,786 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, f17c492d-8495-452c-8983-a627ddd10e5f, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:18,787 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, f17c492d-8495-452c-8983-a627ddd10e5f, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:18,788 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, f17c492d-8495-452c-8983-a627ddd10e5f, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:19,009 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:19,012 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:19,012 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, f5c1c5d5-4e49-4021-b7b8-63ea5af36deb, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:19,013 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, f5c1c5d5-4e49-4021-b7b8-63ea5af36deb, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:19,015 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, f5c1c5d5-4e49-4021-b7b8-63ea5af36deb, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:19,379 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:19,380 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-12-05 16:22:19,380 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:19,381 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, ee471dbb-85c5-4892-bc33-5c250f8470b0, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:19,381 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, ee471dbb-85c5-4892-bc33-5c250f8470b0, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:19,383 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, ee471dbb-85c5-4892-bc33-5c250f8470b0, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:22,243 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:22,261 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:22,262 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 55cdf06b-25ce-4e8e-8d94-429a7deae875, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:22,263 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 55cdf06b-25ce-4e8e-8d94-429a7deae875, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:22,264 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 55cdf06b-25ce-4e8e-8d94-429a7deae875, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:22,441 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:22,447 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:22,448 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 2862731e-8380-4b2f-a879-6e2de723b315, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:22,457 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 2862731e-8380-4b2f-a879-6e2de723b315, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:22,466 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 2862731e-8380-4b2f-a879-6e2de723b315, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:22,909 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:22,910 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-12-05 16:22:22,910 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:22,911 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 4559fec4-814e-43fb-bd3f-b9f3e038a8f1, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:22,912 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 4559fec4-814e-43fb-bd3f-b9f3e038a8f1, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:22,934 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 4559fec4-814e-43fb-bd3f-b9f3e038a8f1, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:25,762 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:25,762 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:25,814 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, e5d57917-8480-452e-9013-c1f1577dd76c, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:25,815 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, e5d57917-8480-452e-9013-c1f1577dd76c, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:25,827 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, e5d57917-8480-452e-9013-c1f1577dd76c, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:25,998 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:26,000 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:26,001 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, e8fb25ed-5ab0-42b1-8af9-186e10ef0ec8, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:26,002 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, e8fb25ed-5ab0-42b1-8af9-186e10ef0ec8, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:26,003 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, e8fb25ed-5ab0-42b1-8af9-186e10ef0ec8, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:26,424 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:26,426 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-12-05 16:22:26,426 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:26,427 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 633f6ba4-2b20-43d0-a4f0-87d28895b892, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:26,427 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 633f6ba4-2b20-43d0-a4f0-87d28895b892, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:26,428 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 633f6ba4-2b20-43d0-a4f0-87d28895b892, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:28,988 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:28,988 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:28,989 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 78a0f684-7830-478c-b423-b3889d4fbeb1, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:28,990 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 78a0f684-7830-478c-b423-b3889d4fbeb1, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:28,991 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 78a0f684-7830-478c-b423-b3889d4fbeb1, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:29,210 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:29,211 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:29,211 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 9082b8ba-cedb-4e63-86e4-43bcdf51bac1, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:29,212 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 9082b8ba-cedb-4e63-86e4-43bcdf51bac1, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:29,213 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 9082b8ba-cedb-4e63-86e4-43bcdf51bac1, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:29,704 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:29,705 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-12-05 16:22:29,723 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:29,724 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 8b226faf-529c-4f43-9bce-715b0ed9b0e2, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:29,725 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 8b226faf-529c-4f43-9bce-715b0ed9b0e2, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:29,726 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 8b226faf-529c-4f43-9bce-715b0ed9b0e2, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:32,669 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:32,670 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:32,671 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 05983f11-4e27-48b1-8bd9-954ce085532c, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:32,671 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 05983f11-4e27-48b1-8bd9-954ce085532c, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:32,673 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 05983f11-4e27-48b1-8bd9-954ce085532c, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:32,870 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:32,871 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:32,871 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 1187fc94-9952-4bfc-94e9-ac99afbb3696, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:32,872 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 1187fc94-9952-4bfc-94e9-ac99afbb3696, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:32,873 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 1187fc94-9952-4bfc-94e9-ac99afbb3696, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:33,373 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:33,373 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-12-05 16:22:33,374 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:33,374 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 1066494a-1c6e-411d-a8d4-e4d26450bde0, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:33,375 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 1066494a-1c6e-411d-a8d4-e4d26450bde0, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:33,376 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 1066494a-1c6e-411d-a8d4-e4d26450bde0, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:36,012 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:36,013 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:36,013 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 93650d98-fc34-418b-ab13-8d4020037b93, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:36,014 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 93650d98-fc34-418b-ab13-8d4020037b93, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:36,015 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 93650d98-fc34-418b-ab13-8d4020037b93, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:36,198 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:36,199 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:36,199 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 53c3d6b6-623e-4295-809a-96ce7fa62313, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:36,200 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 53c3d6b6-623e-4295-809a-96ce7fa62313, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:36,201 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 53c3d6b6-623e-4295-809a-96ce7fa62313, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:36,689 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:36,689 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_21_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-12-05 16:22:36,690 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:36,690 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, f3269779-378d-4fdb-8008-2eafdca8f568, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:36,691 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, f3269779-378d-4fdb-8008-2eafdca8f568, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_21_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:36,692 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, f3269779-378d-4fdb-8008-2eafdca8f568, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:37,510 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:37,510 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:37,511 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 1cfbc367-398d-4537-b102-a0f507fa1d73, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:37,511 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 1cfbc367-398d-4537-b102-a0f507fa1d73, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:37,512 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 1cfbc367-398d-4537-b102-a0f507fa1d73, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:37,727 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:37,728 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:37,728 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 29f4d0fd-23f6-43bb-8297-83d12317bac5, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:37,729 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 29f4d0fd-23f6-43bb-8297-83d12317bac5, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:37,730 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 29f4d0fd-23f6-43bb-8297-83d12317bac5, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:37,947 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:37,948 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:37,948 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 9bd1025f-0c5c-41cd-954f-130c41df26c5, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:37,949 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 9bd1025f-0c5c-41cd-954f-130c41df26c5, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_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:37,950 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 9bd1025f-0c5c-41cd-954f-130c41df26c5, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:40,357 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:50,387 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:50,388 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 28e3cbb3-9e96-43dd-9bc0-129ce7994c7d, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:50,389 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 28e3cbb3-9e96-43dd-9bc0-129ce7994c7d, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_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:50,391 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 28e3cbb3-9e96-43dd-9bc0-129ce7994c7d, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:53,064 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:53,114 - INFO - root.23341  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_21_compression`, volumes `value` (8000000, 1595704)
2025-12-05 16:22:53,114 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:53,115 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 8c1e270c-ef43-456a-a03f-56fb46d2a6a0, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:53,115 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 8c1e270c-ef43-456a-a03f-56fb46d2a6a0, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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_21_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:22:53,116 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 8c1e270c-ef43-456a-a03f-56fb46d2a6a0, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:53,374 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:53,386 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:22:53,387 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 95899d78-ed2c-4664-8371-dfdf526cd439, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:22:53,388 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 95899d78-ed2c-4664-8371-dfdf526cd439, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_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:53,389 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 95899d78-ed2c-4664-8371-dfdf526cd439, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:56,083 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:23:06,124 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:23:06,125 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 12d60653-97b0-48ae-b40a-368a00af98a7, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:23:06,126 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 12d60653-97b0-48ae-b40a-368a00af98a7, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_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:23:06,128 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 12d60653-97b0-48ae-b40a-368a00af98a7, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:23:07,916 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:23:17,303 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:23:17,304 - DEBUG - ydb.connection.23341  - 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:23:17,310 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:23:17,311 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ListEndpoints, 0b9529a3-3de9-4f2d-ad5c-b3e1761bdeb8, localhost:3870): creating call state
2025-12-05 16:23:17,312 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ListEndpoints, 0b9529a3-3de9-4f2d-ad5c-b3e1761bdeb8, localhost:3870): request = { database: "/Root" }
2025-12-05 16:23:17,315 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ListEndpoints, 0b9529a3-3de9-4f2d-ad5c-b3e1761bdeb8, localhost:3870): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 3870 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:23:17,316 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:3870, location 1, ssl: False>]>
2025-12-05 16:23:17,316 - DEBUG - ydb.connection.23341  - close: Closing channel for endpoint localhost:3870
2025-12-05 16:23:17,940 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:23:17,941 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, f56de342-2971-46bd-86a6-8fd4e801c65c, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:23:17,941 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, f56de342-2971-46bd-86a6-8fd4e801c65c, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_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:23:17,942 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, f56de342-2971-46bd-86a6-8fd4e801c65c, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:23:18,385 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:23:28,408 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:23:28,409 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, ffa5749e-5d2d-4c47-a16d-13da1fa3b066, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:23:28,410 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, ffa5749e-5d2d-4c47-a16d-13da1fa3b066, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_21_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:23:28,420 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, ffa5749e-5d2d-4c47-a16d-13da1fa3b066, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:23:28,881 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:23:28,893 - INFO - root.23341  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_21_compression`, volumes `value` (8000000, 1465928)
2025-12-05 16:23:28,901 - INFO - root.23341  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_21_compression` 8092160 / 1465928: 5.520161972484324
2025-12-05 16:23:28,913 - INFO - ya.test.23341  - pytest_runtest_teardown: Test teardown
2025-12-05 16:23:28,915 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(DeleteSession, fbb85d32-631d-4685-89dd-d391ca0fb3a0, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:23:28,916 - DEBUG - ydb.connection.23341  - _log_request: RpcState(DeleteSession, fbb85d32-631d-4685-89dd-d391ca0fb3a0, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" }
2025-12-05 16:23:28,919 - DEBUG - ydb.connection.23341  - _log_response: RpcState(DeleteSession, fbb85d32-631d-4685-89dd-d391ca0fb3a0, ghrun-a66hhqwkt4.auto.internal:3870): response = { status: SUCCESS }
2025-12-05 16:23:28,920 - DEBUG - ydb.query.pool.23341  - stop: All session were deleted.
2025-12-05 16:23:28,921 - DEBUG - ydb.connection.23341  - _rpc_error_handler: RpcState(AttachSession, 3ea465c3-c111-4992-ad88-83ad279e2c32, ghrun-a66hhqwkt4.auto.internal:3870): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-05 16:23:28,922 - DEBUG - ydb.topic.23341  - close: Close topic client
2025-12-05 16:23:28,923 - DEBUG - ydb.topic.23341  - close: Topic client was closed
2025-12-05 16:23:28,933 - DEBUG - ydb.connection.23341  - close: Closing channel for endpoint localhost:3870
2025-12-05 16:23:28,934 - DEBUG - ydb.connection.23341  - close: Closing channel for endpoint ghrun-a66hhqwkt4.auto.internal:3870
2025-12-05 16:23:28,935 - INFO - ydb.pool.Discovery.23341  - run: Successfully terminated discovery process
2025-12-05 16:23:40,937 - INFO - ydb.tests.library.harness.kikimr_runner.23341  - stop: Stopped node localhost:3870/1
2025-12-05 16:23:40,965 - DEBUG - library.python.filelock.23341  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24191
2025-12-05 16:23:40,968 - DEBUG - library.python.filelock.23341  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23650
2025-12-05 16:23:40,968 - DEBUG - library.python.filelock.23341  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16018
2025-12-05 16:23:40,968 - DEBUG - library.python.filelock.23341  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3870
2025-12-05 16:23:40,969 - DEBUG - library.python.filelock.23341  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27369
