2025-11-21 14:10:54,787 - INFO - ya.test.1125866  - pytest_runtest_setup: ####################################################################################################
2025-11-21 14:10:54,788 - INFO - ya.test.1125866  - pytest_runtest_setup: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11]
2025-11-21 14:10:54,788 - INFO - ya.test.1125866  - pytest_runtest_setup: ####################################################################################################
2025-11-21 14:10:54,788 - INFO - ya.test.1125866  - pytest_runtest_setup: Test setup
2025-11-21 14:10:54,790 - INFO - ya.test.1125866  - 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-11-21 14:10:54,796 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:10:54,799 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 3bf5d2a9-e355-43c1-b9c9-15381523c325, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:10:54,800 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 3bf5d2a9-e355-43c1-b9c9-15381523c325, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:10:54,802 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 3bf5d2a9-e355-43c1-b9c9-15381523c325, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:00,474 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:00,475 - INFO - __tests__.alter_compression.1125866  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_11_compression created
2025-11-21 14:11:00,476 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:00,476 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 0daceccf-ce62-4b1c-a373-1f2fe2f50134, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:00,477 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 0daceccf-ce62-4b1c-a373-1f2fe2f50134, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:00,480 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 0daceccf-ce62-4b1c-a373-1f2fe2f50134, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:02,951 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:02,952 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:02,953 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, e4e34def-95ac-4edd-9cd8-56b73c9b69d0, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:02,954 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, e4e34def-95ac-4edd-9cd8-56b73c9b69d0, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:02,956 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, e4e34def-95ac-4edd-9cd8-56b73c9b69d0, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:07,612 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:07,613 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:07,614 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, d69ca6e4-fd79-4794-8f73-b029adc004f2, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:07,614 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, d69ca6e4-fd79-4794-8f73-b029adc004f2, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:07,616 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, d69ca6e4-fd79-4794-8f73-b029adc004f2, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:10,837 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:10,838 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:10,839 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 114896d8-c776-40f5-9a28-f4ede7f02594, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:10,840 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 114896d8-c776-40f5-9a28-f4ede7f02594, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:10,842 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 114896d8-c776-40f5-9a28-f4ede7f02594, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:17,718 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:17,719 - INFO - __tests__.alter_compression.1125866  - 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-11-21 14:11:17,720 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:17,721 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 54158335-1778-454c-8925-744b819dbe10, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:17,723 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 54158335-1778-454c-8925-744b819dbe10, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:17,724 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 54158335-1778-454c-8925-744b819dbe10, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:23,045 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:23,136 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:23,148 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 87de14b7-387d-427b-87f0-5c540c44db92, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:23,149 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 87de14b7-387d-427b-87f0-5c540c44db92, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:23,150 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 87de14b7-387d-427b-87f0-5c540c44db92, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:24,055 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:24,056 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:24,057 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, e321882e-826a-45ec-9b0e-6a36f54f39c8, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:24,058 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, e321882e-826a-45ec-9b0e-6a36f54f39c8, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:24,080 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, e321882e-826a-45ec-9b0e-6a36f54f39c8, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:25,465 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:25,466 - INFO - __tests__.alter_compression.1125866  - 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-11-21 14:11:25,467 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:25,468 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 61bc56e0-533d-4c99-85e4-14b9ca472847, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:25,469 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 61bc56e0-533d-4c99-85e4-14b9ca472847, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:25,477 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 61bc56e0-533d-4c99-85e4-14b9ca472847, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:30,439 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:30,440 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:30,441 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 09570657-2bd1-419f-adf9-4755e3c2e355, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:30,442 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 09570657-2bd1-419f-adf9-4755e3c2e355, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:30,444 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 09570657-2bd1-419f-adf9-4755e3c2e355, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:31,160 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:31,161 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:31,161 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, a6cba2cd-1987-4c9c-a379-1b0c279f4108, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:31,162 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, a6cba2cd-1987-4c9c-a379-1b0c279f4108, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:31,164 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, a6cba2cd-1987-4c9c-a379-1b0c279f4108, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:32,307 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:32,308 - INFO - __tests__.alter_compression.1125866  - 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-11-21 14:11:32,309 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:32,310 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 8b35d9bb-b6c6-4a31-a727-3638f7c273ca, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:32,319 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 8b35d9bb-b6c6-4a31-a727-3638f7c273ca, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:32,321 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 8b35d9bb-b6c6-4a31-a727-3638f7c273ca, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:35,983 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:35,984 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:35,985 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, a75b4964-912b-41bc-814d-c6c4e738eec1, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:35,986 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, a75b4964-912b-41bc-814d-c6c4e738eec1, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:35,992 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, a75b4964-912b-41bc-814d-c6c4e738eec1, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:36,468 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:36,469 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:36,470 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 2c069650-7441-4c34-94f3-892fd9191678, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:36,471 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 2c069650-7441-4c34-94f3-892fd9191678, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:36,473 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 2c069650-7441-4c34-94f3-892fd9191678, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:37,317 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:37,317 - INFO - __tests__.alter_compression.1125866  - 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-11-21 14:11:37,318 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:37,319 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 128206e3-504f-47a6-8ae8-d70178209337, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:37,320 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 128206e3-504f-47a6-8ae8-d70178209337, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:37,327 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 128206e3-504f-47a6-8ae8-d70178209337, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:40,834 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:40,835 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:40,836 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, c4c1de97-3679-49c4-9b0c-f041b48123bb, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:40,837 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, c4c1de97-3679-49c4-9b0c-f041b48123bb, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:40,842 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, c4c1de97-3679-49c4-9b0c-f041b48123bb, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:41,775 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:41,776 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:41,776 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, abdd772b-8604-4ba9-8cca-6a001871b324, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:41,777 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, abdd772b-8604-4ba9-8cca-6a001871b324, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:41,780 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, abdd772b-8604-4ba9-8cca-6a001871b324, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:43,076 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:43,077 - INFO - __tests__.alter_compression.1125866  - 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-11-21 14:11:43,077 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:43,078 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, e16aec10-d4ce-4ea8-aa69-2a98b28f545a, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:43,080 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, e16aec10-d4ce-4ea8-aa69-2a98b28f545a, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:43,082 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, e16aec10-d4ce-4ea8-aa69-2a98b28f545a, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:47,258 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:47,259 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:47,260 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, dd14b58e-8b9c-4574-aa2f-21355beaad00, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:47,261 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, dd14b58e-8b9c-4574-aa2f-21355beaad00, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:47,262 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, dd14b58e-8b9c-4574-aa2f-21355beaad00, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:47,607 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:47,608 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:47,609 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 7fff8239-d0ed-4a92-8cf0-04d83c6f659c, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:47,610 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 7fff8239-d0ed-4a92-8cf0-04d83c6f659c, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:47,612 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 7fff8239-d0ed-4a92-8cf0-04d83c6f659c, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:49,752 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:49,753 - INFO - __tests__.alter_compression.1125866  - 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-11-21 14:11:49,753 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:49,754 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 3d71b211-7608-4d0c-a2b9-432f2ac2fce1, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:49,761 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 3d71b211-7608-4d0c-a2b9-432f2ac2fce1, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:49,763 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 3d71b211-7608-4d0c-a2b9-432f2ac2fce1, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:53,541 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:53,542 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:53,542 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 136bea21-deca-4315-912d-ff7f4568da19, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:53,543 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 136bea21-deca-4315-912d-ff7f4568da19, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:53,546 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 136bea21-deca-4315-912d-ff7f4568da19, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:54,005 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:54,006 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:54,007 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, c09182b8-07b7-472d-83d9-05e5ea59e7f1, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:54,008 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, c09182b8-07b7-472d-83d9-05e5ea59e7f1, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:54,010 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, c09182b8-07b7-472d-83d9-05e5ea59e7f1, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:55,246 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:55,247 - INFO - __tests__.alter_compression.1125866  - 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-11-21 14:11:55,247 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:55,248 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 95bac93b-cf66-4b0b-8680-4161735a91ba, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:55,249 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 95bac93b-cf66-4b0b-8680-4161735a91ba, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:55,250 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 95bac93b-cf66-4b0b-8680-4161735a91ba, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:58,250 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:58,251 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:58,252 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, cac8e4b5-490d-4e2e-b484-4f3d356e9a35, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:58,253 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, cac8e4b5-490d-4e2e-b484-4f3d356e9a35, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:58,254 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, cac8e4b5-490d-4e2e-b484-4f3d356e9a35, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:58,590 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:58,591 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:58,650 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 4879dfa9-701a-4668-ba19-c905651935d8, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:58,651 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 4879dfa9-701a-4668-ba19-c905651935d8, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:58,653 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 4879dfa9-701a-4668-ba19-c905651935d8, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:11:59,320 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1125866  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 14:11:59,321 - DEBUG - ydb.connection.1125866  - 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-11-21 14:11:59,360 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1125866  - context_resolve: Resolving endpoints for database /Root
2025-11-21 14:11:59,361 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ListEndpoints, 628819d5-310d-4602-a508-10b79d2e72d2, localhost:3810): creating call state
2025-11-21 14:11:59,361 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ListEndpoints, 628819d5-310d-4602-a508-10b79d2e72d2, localhost:3810): request = { database: "/Root" }
2025-11-21 14:11:59,389 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ListEndpoints, 628819d5-310d-4602-a508-10b79d2e72d2, localhost:3810): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-acwccf6kvy.auto.internal" port: 3810 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-11-21 14:11:59,390 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1125866  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-acwccf6kvy.auto.internal:3810, location 1, ssl: False>]>
2025-11-21 14:11:59,391 - DEBUG - ydb.connection.1125866  - close: Closing channel for endpoint localhost:3810
2025-11-21 14:11:59,871 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:59,872 - INFO - __tests__.alter_compression.1125866  - 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-11-21 14:11:59,872 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:11:59,873 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, da33f2d1-1b3b-4a84-81a2-5d251a5c3771, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:11:59,874 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, da33f2d1-1b3b-4a84-81a2-5d251a5c3771, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:11:59,884 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, da33f2d1-1b3b-4a84-81a2-5d251a5c3771, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:03,526 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:03,527 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:03,528 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, e5455535-fe42-4d4b-baf1-e750ba8af75b, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:03,529 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, e5455535-fe42-4d4b-baf1-e750ba8af75b, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:03,530 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, e5455535-fe42-4d4b-baf1-e750ba8af75b, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:03,809 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:03,810 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:03,814 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, c5296966-6e9a-41be-b79d-e2122d6bfda7, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:03,815 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, c5296966-6e9a-41be-b79d-e2122d6bfda7, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:03,820 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, c5296966-6e9a-41be-b79d-e2122d6bfda7, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:04,818 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:04,819 - INFO - __tests__.alter_compression.1125866  - 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-11-21 14:12:04,820 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:04,821 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 23e02494-e9c0-4ac6-bdc3-55adea2b6e70, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:04,821 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 23e02494-e9c0-4ac6-bdc3-55adea2b6e70, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:04,824 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 23e02494-e9c0-4ac6-bdc3-55adea2b6e70, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:07,767 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:07,768 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:07,769 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 6fa01a0e-b764-4d75-8600-10fa0c17a388, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:07,769 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 6fa01a0e-b764-4d75-8600-10fa0c17a388, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:07,773 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 6fa01a0e-b764-4d75-8600-10fa0c17a388, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:08,212 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:08,212 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:08,213 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, a789ea16-338c-49bd-97bb-322fa05902b6, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:08,214 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, a789ea16-338c-49bd-97bb-322fa05902b6, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:08,215 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, a789ea16-338c-49bd-97bb-322fa05902b6, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:09,378 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:09,379 - INFO - __tests__.alter_compression.1125866  - 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-11-21 14:12:09,380 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:09,380 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 9e3399d3-2e70-438b-bdf5-68310d4236a8, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:09,381 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 9e3399d3-2e70-438b-bdf5-68310d4236a8, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:09,382 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 9e3399d3-2e70-438b-bdf5-68310d4236a8, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:11,895 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:11,896 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:11,896 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, c23207aa-e1db-4331-b61e-08c39224457c, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:11,897 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, c23207aa-e1db-4331-b61e-08c39224457c, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:11,898 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, c23207aa-e1db-4331-b61e-08c39224457c, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:12,398 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:12,398 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:12,399 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, a11508b3-5e77-464f-a7be-337063e16af1, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:12,400 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, a11508b3-5e77-464f-a7be-337063e16af1, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:12,404 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, a11508b3-5e77-464f-a7be-337063e16af1, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:12,774 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:12,775 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:12,775 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 06c82114-c3b3-4271-9745-292e32d60dc8, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:12,776 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 06c82114-c3b3-4271-9745-292e32d60dc8, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:12,777 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 06c82114-c3b3-4271-9745-292e32d60dc8, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:19,309 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:29,358 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:29,360 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, cbfbcda8-4ebc-4070-9a95-e4d16998bad7, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:29,360 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, cbfbcda8-4ebc-4070-9a95-e4d16998bad7, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:29,362 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, cbfbcda8-4ebc-4070-9a95-e4d16998bad7, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:35,243 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:35,300 - INFO - root.1125866  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1884800)
2025-11-21 14:12:35,301 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:35,302 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, 6ea926cb-eb9d-4506-a32b-31c8646e412a, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:35,303 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, 6ea926cb-eb9d-4506-a32b-31c8646e412a, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:35,304 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, 6ea926cb-eb9d-4506-a32b-31c8646e412a, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:36,145 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:36,145 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:36,146 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, d01db82f-9366-413c-b8d6-d31bc92899c0, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:36,147 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, d01db82f-9366-413c-b8d6-d31bc92899c0, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:36,160 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, d01db82f-9366-413c-b8d6-d31bc92899c0, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:43,123 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:53,205 - DEBUG - ydb.query.pool.1125866  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:53,206 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(ExecuteQuery, b1148b69-972c-4936-9cbf-eae6f2ebfb90, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:53,207 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(ExecuteQuery, b1148b69-972c-4936-9cbf-eae6f2ebfb90, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" 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 }
2025-11-21 14:12:53,209 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(ExecuteQuery, b1148b69-972c-4936-9cbf-eae6f2ebfb90, ghrun-acwccf6kvy.auto.internal:3810): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:12:57,338 - DEBUG - ydb.query.pool.1125866  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=
2025-11-21 14:12:57,414 - INFO - root.1125866  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1884800)
2025-11-21 14:12:57,415 - INFO - root.1125866  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_11_compression` 8092160 / 1884800: 4.2933786078098475
2025-11-21 14:12:57,428 - INFO - ya.test.1125866  - pytest_runtest_teardown: Test teardown
2025-11-21 14:12:57,429 - DEBUG - ydb.connection.1125866  - _prepare_call: RpcState(DeleteSession, e92a1fb5-1040-4832-9c69-7abd4fea99b6, ghrun-acwccf6kvy.auto.internal:3810): creating call state
2025-11-21 14:12:57,430 - DEBUG - ydb.connection.1125866  - _log_request: RpcState(DeleteSession, e92a1fb5-1040-4832-9c69-7abd4fea99b6, ghrun-acwccf6kvy.auto.internal:3810): request = { session_id: "ydb://session/3?node_id=1&id=NjJlNjVjY2ItY2YxZWQzZTctNjM5ZjY5NjgtZmViNDk3ODE=" }
2025-11-21 14:12:57,447 - DEBUG - ydb.connection.1125866  - _log_response: RpcState(DeleteSession, e92a1fb5-1040-4832-9c69-7abd4fea99b6, ghrun-acwccf6kvy.auto.internal:3810): response = { status: SUCCESS }
2025-11-21 14:12:57,449 - DEBUG - ydb.query.pool.1125866  - stop: All session were deleted.
2025-11-21 14:12:57,450 - DEBUG - ydb.topic.1125866  - close: Close topic client
2025-11-21 14:12:57,450 - DEBUG - ydb.topic.1125866  - close: Topic client was closed
2025-11-21 14:12:57,456 - DEBUG - ydb.connection.1125866  - close: Closing channel for endpoint localhost:3810
2025-11-21 14:12:57,457 - DEBUG - ydb.connection.1125866  - close: Closing channel for endpoint ghrun-acwccf6kvy.auto.internal:3810
2025-11-21 14:12:57,462 - INFO - ydb.pool.Discovery.1125866  - run: Successfully terminated discovery process
2025-11-21 14:12:57,464 - DEBUG - ydb.connection.1125866  - _rpc_error_handler: RpcState(AttachSession, 76cea07a-1f17-4ada-a108-c4b0b2068256, ghrun-acwccf6kvy.auto.internal:3810): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-21 14:13:19,507 - INFO - ydb.tests.library.harness.kikimr_runner.1125866  - stop: Stopped node localhost:3810/1
2025-11-21 14:13:19,515 - DEBUG - library.python.filelock.1125866  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26810
2025-11-21 14:13:19,516 - DEBUG - library.python.filelock.1125866  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7651
2025-11-21 14:13:19,516 - DEBUG - library.python.filelock.1125866  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17630
2025-11-21 14:13:19,516 - DEBUG - library.python.filelock.1125866  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3810
2025-11-21 14:13:19,517 - DEBUG - library.python.filelock.1125866  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23730
