2025-12-09 12:54:20,280 - INFO - ya.test.1986964  - pytest_runtest_setup: ####################################################################################################
2025-12-09 12:54:20,280 - INFO - ya.test.1986964  - pytest_runtest_setup: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11]
2025-12-09 12:54:20,281 - INFO - ya.test.1986964  - pytest_runtest_setup: ####################################################################################################
2025-12-09 12:54:20,281 - INFO - ya.test.1986964  - pytest_runtest_setup: Test setup
2025-12-09 12:54:20,300 - INFO - ya.test.1986964  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11])
2025-12-09 12:54:20,301 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:20,302 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 898d1802-eb61-49c1-a3a9-5362b387f936, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:20,303 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 898d1802-eb61-49c1-a3a9-5362b387f936, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_11_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 11)\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-09 12:54:20,304 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 898d1802-eb61-49c1-a3a9-5362b387f936, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:22,850 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:22,851 - INFO - __tests__.alter_compression.1986964  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_11_compression created
2025-12-09 12:54:22,852 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:22,852 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 1c3cc43b-6f18-47eb-829e-0b1aef9ed100, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:22,853 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 1c3cc43b-6f18-47eb-829e-0b1aef9ed100, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_11_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:54:22,855 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 1c3cc43b-6f18-47eb-829e-0b1aef9ed100, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:23,620 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:23,620 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:23,621 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, a824b2a1-e829-4ae7-991d-7e5ffdc039d0, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:23,622 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, a824b2a1-e829-4ae7-991d-7e5ffdc039d0, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:23,623 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, a824b2a1-e829-4ae7-991d-7e5ffdc039d0, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:26,294 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:26,295 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:26,295 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, bc96ecd3-2eae-4f06-8e3a-a0a6b8d87d7b, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:26,296 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, bc96ecd3-2eae-4f06-8e3a-a0a6b8d87d7b, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:26,298 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, bc96ecd3-2eae-4f06-8e3a-a0a6b8d87d7b, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:27,474 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:27,475 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:27,476 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, dcedaff0-400d-485a-8016-58b7f5c1fbaf, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:27,477 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, dcedaff0-400d-485a-8016-58b7f5c1fbaf, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:27,478 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, dcedaff0-400d-485a-8016-58b7f5c1fbaf, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:29,410 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:29,411 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 375776, 'BlobCount': 128}}
2025-12-09 12:54:29,411 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:29,412 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 62a5c294-de48-42ff-bd2c-70df032f71e1, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:29,413 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 62a5c294-de48-42ff-bd2c-70df032f71e1, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:29,419 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 62a5c294-de48-42ff-bd2c-70df032f71e1, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:31,850 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:31,850 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:31,851 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 45699890-01b8-47f3-a555-43850b520ad4, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:31,852 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 45699890-01b8-47f3-a555-43850b520ad4, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:31,857 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 45699890-01b8-47f3-a555-43850b520ad4, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:32,346 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:32,347 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:32,348 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, a2e4259a-1a28-4889-9df2-8ad4fc0dede8, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:32,349 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, a2e4259a-1a28-4889-9df2-8ad4fc0dede8, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:32,350 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, a2e4259a-1a28-4889-9df2-8ad4fc0dede8, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:32,809 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:32,810 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 753008, 'BlobCount': 256}}
2025-12-09 12:54:32,810 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:32,811 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 7afe3833-9b99-4934-8d59-964c6fbd1ed7, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:32,812 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 7afe3833-9b99-4934-8d59-964c6fbd1ed7, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:32,815 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 7afe3833-9b99-4934-8d59-964c6fbd1ed7, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:35,306 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:35,307 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:35,307 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, af48eca0-da1e-4650-aa13-62128841e657, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:35,308 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, af48eca0-da1e-4650-aa13-62128841e657, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:35,314 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, af48eca0-da1e-4650-aa13-62128841e657, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:36,098 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:36,099 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:36,100 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 2500222f-bfaa-44cc-9cf4-6399cf23547b, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:36,101 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 2500222f-bfaa-44cc-9cf4-6399cf23547b, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:36,102 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 2500222f-bfaa-44cc-9cf4-6399cf23547b, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:36,803 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:36,803 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 1129888, 'BlobCount': 384}}
2025-12-09 12:54:36,804 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:36,805 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, fea759ef-a084-4e92-87a0-f7fd86c00666, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:36,806 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, fea759ef-a084-4e92-87a0-f7fd86c00666, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:36,808 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, fea759ef-a084-4e92-87a0-f7fd86c00666, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:41,206 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:41,207 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:41,208 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, efac8d34-97b8-4eb8-b18a-51624935f2e0, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:41,209 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, efac8d34-97b8-4eb8-b18a-51624935f2e0, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:41,211 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, efac8d34-97b8-4eb8-b18a-51624935f2e0, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:42,195 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:42,196 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:42,197 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, ee793110-b38a-498b-b2c6-5601e668bf5d, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:42,198 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, ee793110-b38a-498b-b2c6-5601e668bf5d, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:42,208 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, ee793110-b38a-498b-b2c6-5601e668bf5d, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:42,956 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:42,957 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1507072, 'BlobCount': 512}}
2025-12-09 12:54:42,957 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:42,958 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 274f2915-0192-454d-808f-c8eaa4cecb20, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:42,960 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 274f2915-0192-454d-808f-c8eaa4cecb20, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:42,962 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 274f2915-0192-454d-808f-c8eaa4cecb20, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:45,362 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:45,363 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:45,364 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, c18d51d7-b642-4ac9-b274-0480cfc7abce, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:45,365 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, c18d51d7-b642-4ac9-b274-0480cfc7abce, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:45,371 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, c18d51d7-b642-4ac9-b274-0480cfc7abce, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:45,778 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:45,779 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:45,780 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, e84d2fab-e5e2-4b61-b087-bc85c5f1dc2b, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:45,782 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, e84d2fab-e5e2-4b61-b087-bc85c5f1dc2b, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:45,783 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, e84d2fab-e5e2-4b61-b087-bc85c5f1dc2b, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:46,363 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:46,364 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1883936, 'BlobCount': 640}}
2025-12-09 12:54:46,364 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:46,365 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, dc02e2cc-3d86-44a9-8ac5-f5925a5fa03e, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:46,365 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, dc02e2cc-3d86-44a9-8ac5-f5925a5fa03e, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:46,367 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, dc02e2cc-3d86-44a9-8ac5-f5925a5fa03e, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:48,541 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:48,542 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:48,543 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 618aa7d5-fd8b-40ca-bfe7-56df21974b62, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:48,544 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 618aa7d5-fd8b-40ca-bfe7-56df21974b62, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:48,545 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 618aa7d5-fd8b-40ca-bfe7-56df21974b62, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:48,843 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:48,844 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:48,845 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 3ee2ed92-4b54-4d5f-8839-b7ceee42483f, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:48,846 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 3ee2ed92-4b54-4d5f-8839-b7ceee42483f, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:48,848 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 3ee2ed92-4b54-4d5f-8839-b7ceee42483f, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:49,438 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:49,439 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 2261136, 'BlobCount': 768}}
2025-12-09 12:54:49,439 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:49,440 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 84d0804a-280f-4112-b32d-ddf5fd69e0aa, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:49,441 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 84d0804a-280f-4112-b32d-ddf5fd69e0aa, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:49,443 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 84d0804a-280f-4112-b32d-ddf5fd69e0aa, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:51,774 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:51,774 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:51,775 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, a73d6e3f-ad1a-4587-bbdf-ad7e50d3caf3, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:51,776 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, a73d6e3f-ad1a-4587-bbdf-ad7e50d3caf3, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:51,777 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, a73d6e3f-ad1a-4587-bbdf-ad7e50d3caf3, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:52,076 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:52,077 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:52,078 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9531048e-e703-44a9-a298-1cc81e158bde, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:52,079 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9531048e-e703-44a9-a298-1cc81e158bde, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:52,081 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9531048e-e703-44a9-a298-1cc81e158bde, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:52,889 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:52,890 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2638112, 'BlobCount': 896}}
2025-12-09 12:54:52,890 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:52,891 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, f7a7c6f4-6201-452c-8723-694d127e4b62, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:52,892 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, f7a7c6f4-6201-452c-8723-694d127e4b62, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:52,894 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, f7a7c6f4-6201-452c-8723-694d127e4b62, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:55,344 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:55,345 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:55,346 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 301978aa-732e-4063-a02f-bea60c2bf78f, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:55,347 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 301978aa-732e-4063-a02f-bea60c2bf78f, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:55,348 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 301978aa-732e-4063-a02f-bea60c2bf78f, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:55,677 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:55,678 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:55,679 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 557fb6d1-2dec-4a07-a682-0f73ac74fa27, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:55,680 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 557fb6d1-2dec-4a07-a682-0f73ac74fa27, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:55,684 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 557fb6d1-2dec-4a07-a682-0f73ac74fa27, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:56,218 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 12:54:56,218 - DEBUG - ydb.connection.1986964  - 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-09 12:54:56,232 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - context_resolve: Resolving endpoints for database /Root
2025-12-09 12:54:56,237 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ListEndpoints, 7105fd05-4a3e-4550-b0ed-403386dd0ff8, localhost:63675): creating call state
2025-12-09 12:54:56,238 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ListEndpoints, 7105fd05-4a3e-4550-b0ed-403386dd0ff8, localhost:63675): request = { database: "/Root" }
2025-12-09 12:54:56,280 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ListEndpoints, 7105fd05-4a3e-4550-b0ed-403386dd0ff8, localhost:63675): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7wapoxkjru.auto.internal" port: 63675 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-09 12:54:56,281 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7wapoxkjru.auto.internal:63675, location 1, ssl: False>]>
2025-12-09 12:54:56,281 - DEBUG - ydb.connection.1986964  - close: Closing channel for endpoint localhost:63675
2025-12-09 12:54:56,412 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:56,413 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 3015280, 'BlobCount': 1024}}
2025-12-09 12:54:56,414 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:56,415 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, d1b296b4-de8d-433c-abb5-dece6693afbc, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:56,416 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, d1b296b4-de8d-433c-abb5-dece6693afbc, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:56,418 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, d1b296b4-de8d-433c-abb5-dece6693afbc, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:58,876 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:58,877 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:58,878 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 4a48becf-d8f5-4d8d-8154-d4ab03c7a4b8, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:58,879 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 4a48becf-d8f5-4d8d-8154-d4ab03c7a4b8, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:58,881 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 4a48becf-d8f5-4d8d-8154-d4ab03c7a4b8, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:59,139 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:59,140 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:59,141 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 23667066-c49c-4208-affe-39998e8cbc10, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:59,142 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 23667066-c49c-4208-affe-39998e8cbc10, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:59,143 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 23667066-c49c-4208-affe-39998e8cbc10, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:54:59,795 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:59,796 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 3392288, 'BlobCount': 1152}}
2025-12-09 12:54:59,797 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:54:59,798 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 40dcefa4-df87-4b10-af39-042ac7c9ad5c, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:54:59,799 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 40dcefa4-df87-4b10-af39-042ac7c9ad5c, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:54:59,801 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 40dcefa4-df87-4b10-af39-042ac7c9ad5c, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:02,079 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:02,080 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:02,081 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9ad05ed8-21f1-410b-a376-fdf1c5aecb62, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:02,082 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9ad05ed8-21f1-410b-a376-fdf1c5aecb62, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:55:02,083 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9ad05ed8-21f1-410b-a376-fdf1c5aecb62, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:02,415 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:02,416 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:02,417 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, b9117582-78b0-487d-832b-c339e6280943, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:02,418 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, b9117582-78b0-487d-832b-c339e6280943, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:55:02,420 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, b9117582-78b0-487d-832b-c339e6280943, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:03,240 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:03,241 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3769600, 'BlobCount': 1280}}
2025-12-09 12:55:03,242 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:03,242 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 21af356b-3062-4abb-b350-2b8802fcebf2, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:03,244 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 21af356b-3062-4abb-b350-2b8802fcebf2, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_11_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:55:03,245 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 21af356b-3062-4abb-b350-2b8802fcebf2, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:04,435 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:04,436 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:04,437 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 43769d6e-c326-46f1-a7b0-6e8e031652fe, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:04,438 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 43769d6e-c326-46f1-a7b0-6e8e031652fe, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:55:04,440 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 43769d6e-c326-46f1-a7b0-6e8e031652fe, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:04,716 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:04,717 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:04,718 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 091ffd73-f291-4a42-992a-2ee74f53c10a, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:04,719 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 091ffd73-f291-4a42-992a-2ee74f53c10a, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:55:04,720 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 091ffd73-f291-4a42-992a-2ee74f53c10a, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:04,993 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:04,994 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:04,995 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 41df6379-176d-48cc-98b7-2911b81e2286, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:04,996 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 41df6379-176d-48cc-98b7-2911b81e2286, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_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-09 12:55:04,998 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 41df6379-176d-48cc-98b7-2911b81e2286, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:08,696 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:18,729 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:18,730 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 6e9e325e-0ce1-45ab-a5af-f4aa62de44c0, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:18,731 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 6e9e325e-0ce1-45ab-a5af-f4aa62de44c0, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_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-09 12:55:18,733 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 6e9e325e-0ce1-45ab-a5af-f4aa62de44c0, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:22,050 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:22,152 - INFO - root.1986964  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1884800)
2025-12-09 12:55:22,169 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:22,170 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, f52d227b-1ee1-4fe5-b13c-5e0dae29ad44, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:22,171 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, f52d227b-1ee1-4fe5-b13c-5e0dae29ad44, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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_11_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-09 12:55:22,179 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, f52d227b-1ee1-4fe5-b13c-5e0dae29ad44, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:23,088 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:23,089 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:23,090 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 2543bbd5-c368-478e-8b17-2199be363751, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:23,092 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 2543bbd5-c368-478e-8b17-2199be363751, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_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-09 12:55:23,094 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 2543bbd5-c368-478e-8b17-2199be363751, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:26,633 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:36,666 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:36,667 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 330a2a6d-a1f8-42e7-a471-ecc2330d5515, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:36,667 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 330a2a6d-a1f8-42e7-a471-ecc2330d5515, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_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-09 12:55:36,669 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 330a2a6d-a1f8-42e7-a471-ecc2330d5515, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:38,244 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:48,258 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:48,259 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, a560e6f2-f3b8-4d3d-8199-e3a86d9554f6, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:48,260 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, a560e6f2-f3b8-4d3d-8199-e3a86d9554f6, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_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-09 12:55:48,262 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, a560e6f2-f3b8-4d3d-8199-e3a86d9554f6, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:55:49,764 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:59,771 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:55:59,772 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 2b493017-b31b-4310-850c-a6fdcdabcd53, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:55:59,773 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 2b493017-b31b-4310-850c-a6fdcdabcd53, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_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-09 12:55:59,775 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 2b493017-b31b-4310-850c-a6fdcdabcd53, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:56:01,073 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:56:01,077 - INFO - root.1986964  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1465344)
2025-12-09 12:56:01,078 - INFO - root.1986964  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_11_compression` 8092160 / 1465344: 5.522361984626135
2025-12-09 12:56:01,080 - INFO - ya.test.1986964  - pytest_runtest_teardown: Test teardown
2025-12-09 12:56:01,081 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(DeleteSession, 514148e1-a028-40de-a638-93b46257cc78, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:56:01,082 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(DeleteSession, 514148e1-a028-40de-a638-93b46257cc78, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" }
2025-12-09 12:56:01,086 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(DeleteSession, 514148e1-a028-40de-a638-93b46257cc78, ghrun-7wapoxkjru.auto.internal:63675): response = { status: SUCCESS }
2025-12-09 12:56:01,087 - DEBUG - ydb.query.pool.1986964  - stop: All session were deleted.
2025-12-09 12:56:01,088 - DEBUG - ydb.topic.1986964  - close: Close topic client
2025-12-09 12:56:01,088 - DEBUG - ydb.topic.1986964  - close: Topic client was closed
2025-12-09 12:56:01,091 - DEBUG - ydb.connection.1986964  - close: Closing channel for endpoint localhost:63675
2025-12-09 12:56:01,094 - DEBUG - ydb.connection.1986964  - _rpc_error_handler: RpcState(AttachSession, f037a614-470c-4957-80ee-c1bb90973639, ghrun-7wapoxkjru.auto.internal:63675): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-09 12:56:01,098 - DEBUG - ydb.connection.1986964  - close: Closing channel for endpoint ghrun-7wapoxkjru.auto.internal:63675
2025-12-09 12:56:01,108 - INFO - ydb.pool.Discovery.1986964  - run: Successfully terminated discovery process
2025-12-09 12:56:18,131 - INFO - ydb.tests.library.harness.kikimr_runner.1986964  - stop: Stopped node localhost:63675/1
2025-12-09 12:56:18,136 - DEBUG - library.python.filelock.1986964  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10369
2025-12-09 12:56:18,138 - DEBUG - library.python.filelock.1986964  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19032
2025-12-09 12:56:18,221 - DEBUG - library.python.filelock.1986964  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4447
2025-12-09 12:56:18,221 - DEBUG - library.python.filelock.1986964  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63675
2025-12-09 12:56:18,221 - DEBUG - library.python.filelock.1986964  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24904
