2025-11-21 14:13:42,136 - INFO - ya.test.1133532  - pytest_runtest_setup: ####################################################################################################
2025-11-21 14:13:42,137 - INFO - ya.test.1133532  - pytest_runtest_setup: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14]
2025-11-21 14:13:42,137 - INFO - ya.test.1133532  - pytest_runtest_setup: ####################################################################################################
2025-11-21 14:13:42,137 - INFO - ya.test.1133532  - pytest_runtest_setup: Test setup
2025-11-21 14:13:42,144 - INFO - ya.test.1133532  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14])
2025-11-21 14:13:42,153 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:42,154 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 38995523-88d0-4518-b9d8-769c7041b0d2, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:13:42,155 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 38995523-88d0-4518-b9d8-769c7041b0d2, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_14_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 14)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:13:42,156 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 38995523-88d0-4518-b9d8-769c7041b0d2, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:13:47,149 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:47,150 - INFO - __tests__.alter_compression.1133532  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_14_compression created
2025-11-21 14:13:47,150 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:47,151 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, bdd38779-4353-4840-961e-c7dd4fbba149, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:13:47,152 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, bdd38779-4353-4840-961e-c7dd4fbba149, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:13:47,154 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, bdd38779-4353-4840-961e-c7dd4fbba149, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:13:48,371 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:48,372 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:48,373 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 382f9649-b545-4411-b5b5-24464d568f90, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:13:48,374 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 382f9649-b545-4411-b5b5-24464d568f90, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:13:48,380 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 382f9649-b545-4411-b5b5-24464d568f90, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:13:53,499 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:53,500 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:53,501 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 99ad18be-83ae-473f-850a-f64c13047297, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:13:53,502 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 99ad18be-83ae-473f-850a-f64c13047297, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:13:53,503 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 99ad18be-83ae-473f-850a-f64c13047297, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:13:54,536 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:54,536 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:54,537 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 55df5538-60aa-4c65-adc9-b2d561ca380b, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:13:54,538 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 55df5538-60aa-4c65-adc9-b2d561ca380b, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:13:54,539 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 55df5538-60aa-4c65-adc9-b2d561ca380b, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:13:56,236 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:56,236 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-11-21 14:13:56,237 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:13:56,237 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 6f1ea8df-1880-4e56-b564-c0fff7edffad, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:13:56,238 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 6f1ea8df-1880-4e56-b564-c0fff7edffad, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:13:56,248 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 6f1ea8df-1880-4e56-b564-c0fff7edffad, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:00,126 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:00,127 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:00,128 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 3c4a4593-e6ad-4db8-bfea-2dd79ec33590, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:00,129 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 3c4a4593-e6ad-4db8-bfea-2dd79ec33590, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:00,133 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 3c4a4593-e6ad-4db8-bfea-2dd79ec33590, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:00,443 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:00,444 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:00,444 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 930d8e03-73ac-408b-84da-b810ed6ec629, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:00,445 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 930d8e03-73ac-408b-84da-b810ed6ec629, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:00,448 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 930d8e03-73ac-408b-84da-b810ed6ec629, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:01,048 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:01,049 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-11-21 14:14:01,049 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:01,050 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 76bb6e02-c7a8-4606-8cec-5b40e24afb21, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:01,051 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 76bb6e02-c7a8-4606-8cec-5b40e24afb21, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:01,052 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 76bb6e02-c7a8-4606-8cec-5b40e24afb21, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:03,875 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:03,876 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:03,877 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, ed04661b-9652-454a-910a-6d31b0f3997e, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:03,878 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, ed04661b-9652-454a-910a-6d31b0f3997e, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:03,879 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, ed04661b-9652-454a-910a-6d31b0f3997e, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:04,201 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:04,202 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:04,203 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, d033506d-0f91-471a-99f7-8f7b33cdd830, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:04,203 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, d033506d-0f91-471a-99f7-8f7b33cdd830, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:04,205 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, d033506d-0f91-471a-99f7-8f7b33cdd830, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:04,547 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:04,548 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-11-21 14:14:04,548 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:04,549 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 1cc7fa76-f596-442b-9672-cf1e89155c4a, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:04,549 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 1cc7fa76-f596-442b-9672-cf1e89155c4a, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:04,551 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 1cc7fa76-f596-442b-9672-cf1e89155c4a, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:06,095 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 14:14:06,096 - DEBUG - ydb.connection.1133532  - 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:14:06,107 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - context_resolve: Resolving endpoints for database /Root
2025-11-21 14:14:06,108 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ListEndpoints, e4c4c43e-a1ef-4cd0-8e1f-43fd2caeaeb4, localhost:24841): creating call state
2025-11-21 14:14:06,109 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ListEndpoints, e4c4c43e-a1ef-4cd0-8e1f-43fd2caeaeb4, localhost:24841): request = { database: "/Root" }
2025-11-21 14:14:06,122 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ListEndpoints, e4c4c43e-a1ef-4cd0-8e1f-43fd2caeaeb4, localhost:24841): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-acwccf6kvy.auto.internal" port: 24841 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:14:06,123 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-acwccf6kvy.auto.internal:24841, location 1, ssl: False>]>
2025-11-21 14:14:06,123 - DEBUG - ydb.connection.1133532  - close: Closing channel for endpoint localhost:24841
2025-11-21 14:14:07,736 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:07,737 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:07,738 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 537b088a-37bc-49fa-bdcf-424726b5a9b5, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:07,739 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 537b088a-37bc-49fa-bdcf-424726b5a9b5, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:07,741 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 537b088a-37bc-49fa-bdcf-424726b5a9b5, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:08,138 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:08,140 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:08,140 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 2b227a86-55f9-44db-9a34-8a838e01bbbc, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:08,141 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 2b227a86-55f9-44db-9a34-8a838e01bbbc, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:08,143 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 2b227a86-55f9-44db-9a34-8a838e01bbbc, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:08,594 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:08,595 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-11-21 14:14:08,596 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:08,596 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 8046b862-9cd9-4a9d-9993-847eda71b53c, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:08,597 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 8046b862-9cd9-4a9d-9993-847eda71b53c, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:08,599 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 8046b862-9cd9-4a9d-9993-847eda71b53c, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:11,350 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:11,351 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:11,352 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 00a037f0-4211-4a76-8ff1-56f783fc7563, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:11,352 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 00a037f0-4211-4a76-8ff1-56f783fc7563, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:11,354 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 00a037f0-4211-4a76-8ff1-56f783fc7563, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:11,699 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:11,700 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:11,704 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, b424a917-7f7d-4445-8f1f-089acd7f0ad8, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:11,705 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, b424a917-7f7d-4445-8f1f-089acd7f0ad8, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:11,707 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, b424a917-7f7d-4445-8f1f-089acd7f0ad8, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:12,253 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:12,253 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-11-21 14:14:12,254 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:12,255 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 6a74f56d-aa3f-43e3-91af-11f9c219894c, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:12,256 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 6a74f56d-aa3f-43e3-91af-11f9c219894c, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:12,257 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 6a74f56d-aa3f-43e3-91af-11f9c219894c, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:15,238 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:15,239 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:15,240 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, a3caf3a2-24bb-47ec-9809-41a9343746af, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:15,241 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, a3caf3a2-24bb-47ec-9809-41a9343746af, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:15,242 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, a3caf3a2-24bb-47ec-9809-41a9343746af, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:15,491 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:15,492 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:15,493 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 4eb7a17b-e9eb-4a5e-bdbe-e54d0089c3f3, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:15,493 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 4eb7a17b-e9eb-4a5e-bdbe-e54d0089c3f3, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:15,495 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 4eb7a17b-e9eb-4a5e-bdbe-e54d0089c3f3, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:16,092 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:16,093 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-11-21 14:14:16,093 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:16,094 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, e33d189a-b78d-4de0-98e0-7aa562a24aba, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:16,096 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, e33d189a-b78d-4de0-98e0-7aa562a24aba, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:16,099 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, e33d189a-b78d-4de0-98e0-7aa562a24aba, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:19,052 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:19,053 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:19,054 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 403d2fc5-5980-49e6-8ac5-16df9d2d8c2c, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:19,055 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 403d2fc5-5980-49e6-8ac5-16df9d2d8c2c, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:19,056 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 403d2fc5-5980-49e6-8ac5-16df9d2d8c2c, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:19,421 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:19,421 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:19,422 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, b136d050-a5f1-46e3-9979-1b245f934448, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:19,423 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, b136d050-a5f1-46e3-9979-1b245f934448, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:19,425 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, b136d050-a5f1-46e3-9979-1b245f934448, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:20,172 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:20,173 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-11-21 14:14:20,173 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:20,174 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 9033dbdf-aaf2-47d4-84ba-b3e13e89766f, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:20,175 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 9033dbdf-aaf2-47d4-84ba-b3e13e89766f, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:20,176 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 9033dbdf-aaf2-47d4-84ba-b3e13e89766f, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:24,228 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:24,229 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:24,230 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 2c693f1b-b8ae-4349-bb65-4a567b96a50e, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:24,230 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 2c693f1b-b8ae-4349-bb65-4a567b96a50e, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:24,232 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 2c693f1b-b8ae-4349-bb65-4a567b96a50e, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:24,719 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:24,720 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:24,721 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 5a8635a4-7fa1-4cfd-953d-0ad5dab564d5, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:24,727 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 5a8635a4-7fa1-4cfd-953d-0ad5dab564d5, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:24,729 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 5a8635a4-7fa1-4cfd-953d-0ad5dab564d5, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:25,696 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:25,696 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-11-21 14:14:25,697 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:25,698 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 18e7c2aa-a30b-4055-a847-540071927625, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:25,703 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 18e7c2aa-a30b-4055-a847-540071927625, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:25,704 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 18e7c2aa-a30b-4055-a847-540071927625, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:29,335 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:29,336 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:29,337 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 0d203515-77ab-487b-88c5-8ef1d4ef3c49, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:29,338 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 0d203515-77ab-487b-88c5-8ef1d4ef3c49, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:29,341 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 0d203515-77ab-487b-88c5-8ef1d4ef3c49, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:29,900 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:29,901 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:29,902 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 1c2fa977-fe8a-412b-bb93-e3e6bb344e65, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:29,903 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 1c2fa977-fe8a-412b-bb93-e3e6bb344e65, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:29,905 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 1c2fa977-fe8a-412b-bb93-e3e6bb344e65, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:30,579 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:30,580 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-11-21 14:14:30,580 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:30,581 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 23d553b4-88f0-435f-989e-59bc190753dc, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:30,582 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 23d553b4-88f0-435f-989e-59bc190753dc, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:30,588 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 23d553b4-88f0-435f-989e-59bc190753dc, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:34,516 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:34,517 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:34,518 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 761c3d87-49ab-46dd-aab0-dae8e7753c17, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:34,518 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 761c3d87-49ab-46dd-aab0-dae8e7753c17, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:34,520 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 761c3d87-49ab-46dd-aab0-dae8e7753c17, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:34,806 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:34,807 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:34,808 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, fd6f20c1-d9ec-467d-b8b3-f68faf72dfc4, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:34,809 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, fd6f20c1-d9ec-467d-b8b3-f68faf72dfc4, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:34,822 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, fd6f20c1-d9ec-467d-b8b3-f68faf72dfc4, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:35,942 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:35,943 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-11-21 14:14:35,944 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:35,944 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 15245ac6-94d6-4c49-bcbd-3bac4bc8919b, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:35,945 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 15245ac6-94d6-4c49-bcbd-3bac4bc8919b, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:35,947 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 15245ac6-94d6-4c49-bcbd-3bac4bc8919b, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:37,198 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:37,199 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:37,201 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 8f525f9d-7eae-49fb-b376-25e0fd454a95, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:37,203 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 8f525f9d-7eae-49fb-b376-25e0fd454a95, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:37,204 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 8f525f9d-7eae-49fb-b376-25e0fd454a95, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:37,515 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:37,516 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:37,517 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 2f248b83-ccd1-490b-9935-e05c9d177453, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:37,518 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 2f248b83-ccd1-490b-9935-e05c9d177453, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:37,519 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 2f248b83-ccd1-490b-9935-e05c9d177453, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:37,837 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:37,838 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:37,839 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 6016c305-c020-409d-b2b4-c56156b3cda0, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:37,840 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 6016c305-c020-409d-b2b4-c56156b3cda0, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:37,844 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 6016c305-c020-409d-b2b4-c56156b3cda0, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:44,102 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:54,180 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:14:54,181 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 3c5bcdc9-c3d3-4f3a-b92e-868130e934e1, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:14:54,182 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 3c5bcdc9-c3d3-4f3a-b92e-868130e934e1, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:14:54,183 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 3c5bcdc9-c3d3-4f3a-b92e-868130e934e1, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:14:57,521 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:15:07,547 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:15:07,548 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 33412bb3-7f97-4ca1-b7ff-7c0eea608fd8, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:15:07,549 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 33412bb3-7f97-4ca1-b7ff-7c0eea608fd8, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:15:07,550 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 33412bb3-7f97-4ca1-b7ff-7c0eea608fd8, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:15:10,362 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:15:20,866 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(DeleteSession, 4b5458fd-1879-4985-86b4-76355c350c84, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:15:20,867 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(DeleteSession, 4b5458fd-1879-4985-86b4-76355c350c84, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" }
2025-11-21 14:15:20,873 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(DeleteSession, 4b5458fd-1879-4985-86b4-76355c350c84, ghrun-acwccf6kvy.auto.internal:24841): response = { status: SUCCESS }
2025-11-21 14:15:20,874 - DEBUG - ydb.query.pool.1133532  - stop: All session were deleted.
2025-11-21 14:15:20,875 - DEBUG - ydb.topic.1133532  - close: Close topic client
2025-11-21 14:15:20,875 - DEBUG - ydb.topic.1133532  - close: Topic client was closed
2025-11-21 14:15:20,875 - DEBUG - ydb.connection.1133532  - close: Closing channel for endpoint localhost:24841
2025-11-21 14:15:20,878 - DEBUG - ydb.connection.1133532  - _rpc_error_handler: RpcState(AttachSession, 2fe33059-b6a3-45c6-a8c2-793efe151f3e, ghrun-acwccf6kvy.auto.internal:24841): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-21 14:15:20,883 - DEBUG - ydb.connection.1133532  - close: Closing channel for endpoint ghrun-acwccf6kvy.auto.internal:24841
2025-11-21 14:15:20,885 - INFO - ydb.pool.Discovery.1133532  - run: Successfully terminated discovery process
2025-11-21 14:15:32,894 - INFO - ydb.tests.library.harness.kikimr_runner.1133532  - stop: Stopped node localhost:24841/1
2025-11-21 14:15:32,898 - DEBUG - library.python.filelock.1133532  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24456
2025-11-21 14:15:32,899 - DEBUG - library.python.filelock.1133532  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13414
2025-11-21 14:15:32,899 - DEBUG - library.python.filelock.1133532  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24455
2025-11-21 14:15:32,899 - DEBUG - library.python.filelock.1133532  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24841
2025-11-21 14:15:32,900 - DEBUG - library.python.filelock.1133532  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28374
