2025-11-21 20:46:05,731 - INFO - ya.test.672158  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:46:05,732 - INFO - ya.test.672158  - pytest_runtest_setup: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19]
2025-11-21 20:46:05,732 - INFO - ya.test.672158  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:46:05,732 - INFO - ya.test.672158  - pytest_runtest_setup: Test setup
2025-11-21 20:46:05,734 - INFO - ya.test.672158  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19])
2025-11-21 20:46:05,735 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:05,736 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, cf32b39b-ed09-4d7a-94a7-da3eed2f7163, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:05,737 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, cf32b39b-ed09-4d7a-94a7-da3eed2f7163, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_19_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 19)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:05,740 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, cf32b39b-ed09-4d7a-94a7-da3eed2f7163, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:13,579 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:13,580 - INFO - __tests__.alter_compression.672158  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_19_compression created
2025-11-21 20:46:13,580 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:13,581 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 040df678-8979-4d22-a35f-9ed26d7d346c, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:13,582 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 040df678-8979-4d22-a35f-9ed26d7d346c, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:13,588 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 040df678-8979-4d22-a35f-9ed26d7d346c, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:15,107 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:15,108 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:15,108 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, a1383441-fb0f-4364-bd9d-67f5ecf06f97, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:15,110 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, a1383441-fb0f-4364-bd9d-67f5ecf06f97, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:15,119 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, a1383441-fb0f-4364-bd9d-67f5ecf06f97, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:23,188 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:23,189 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:23,190 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, bc947a6e-73bc-4349-aa8c-6c959ab50fea, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:23,191 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, bc947a6e-73bc-4349-aa8c-6c959ab50fea, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:23,192 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, bc947a6e-73bc-4349-aa8c-6c959ab50fea, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:24,771 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:24,772 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:24,773 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, e491580a-b3d7-458c-9d9c-6d1c1be97e23, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:24,774 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, e491580a-b3d7-458c-9d9c-6d1c1be97e23, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:24,776 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, e491580a-b3d7-458c-9d9c-6d1c1be97e23, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:27,808 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:27,809 - INFO - __tests__.alter_compression.672158  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-21 20:46:27,810 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:27,811 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 044cb6b1-87fd-4659-861c-78e40583d691, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:27,812 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 044cb6b1-87fd-4659-861c-78e40583d691, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:27,813 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 044cb6b1-87fd-4659-861c-78e40583d691, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:35,084 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:35,085 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:35,086 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 11e5d467-2421-4b37-ab1f-c02bff31de90, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:35,087 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 11e5d467-2421-4b37-ab1f-c02bff31de90, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:35,089 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 11e5d467-2421-4b37-ab1f-c02bff31de90, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:35,743 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:35,744 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:35,745 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 6f0bdc55-c70f-4707-8d4a-8ea5e7827194, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:35,746 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 6f0bdc55-c70f-4707-8d4a-8ea5e7827194, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:35,754 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 6f0bdc55-c70f-4707-8d4a-8ea5e7827194, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:36,776 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:36,777 - INFO - __tests__.alter_compression.672158  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-21 20:46:36,778 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:36,778 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, a9ab338d-309c-4cd8-83f3-85ed692ab73e, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:36,779 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, a9ab338d-309c-4cd8-83f3-85ed692ab73e, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:36,782 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, a9ab338d-309c-4cd8-83f3-85ed692ab73e, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:43,383 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:43,384 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:43,385 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, f490f212-a60b-4668-a117-3df0004fcd8e, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:43,385 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, f490f212-a60b-4668-a117-3df0004fcd8e, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:43,388 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, f490f212-a60b-4668-a117-3df0004fcd8e, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:43,740 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:43,741 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:43,741 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, ef913345-2d08-45d5-87b7-1c118c7c0124, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:43,752 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, ef913345-2d08-45d5-87b7-1c118c7c0124, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:43,754 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, ef913345-2d08-45d5-87b7-1c118c7c0124, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:44,361 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:44,362 - INFO - __tests__.alter_compression.672158  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-21 20:46:44,362 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:44,363 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 50a88ee5-60e6-4f7d-a73c-6152694e0f92, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:44,364 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 50a88ee5-60e6-4f7d-a73c-6152694e0f92, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:44,367 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 50a88ee5-60e6-4f7d-a73c-6152694e0f92, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:50,181 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:50,182 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:50,183 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 22997f47-edf8-4749-adb5-bde45c6cb537, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:50,184 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 22997f47-edf8-4749-adb5-bde45c6cb537, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:50,185 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 22997f47-edf8-4749-adb5-bde45c6cb537, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:50,792 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:50,793 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:50,794 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 196477dd-e4df-4f7f-adb4-d46f1235ab9f, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:50,796 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 196477dd-e4df-4f7f-adb4-d46f1235ab9f, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:50,797 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 196477dd-e4df-4f7f-adb4-d46f1235ab9f, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:51,712 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:51,713 - INFO - __tests__.alter_compression.672158  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-21 20:46:51,714 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:51,714 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 83843004-d1d0-4736-a096-1a5079a5666d, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:51,715 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 83843004-d1d0-4736-a096-1a5079a5666d, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:51,717 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 83843004-d1d0-4736-a096-1a5079a5666d, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:57,920 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:57,921 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:57,922 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 48de4c7e-8d28-4e78-91b9-6145b8d9c846, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:57,922 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 48de4c7e-8d28-4e78-91b9-6145b8d9c846, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:57,924 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 48de4c7e-8d28-4e78-91b9-6145b8d9c846, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:58,496 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:58,497 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:58,498 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 63e13822-e55f-4451-93d4-7a796bd41205, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:58,499 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 63e13822-e55f-4451-93d4-7a796bd41205, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:58,507 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 63e13822-e55f-4451-93d4-7a796bd41205, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:59,150 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:59,151 - INFO - __tests__.alter_compression.672158  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-21 20:46:59,151 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:46:59,152 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 6347bed5-5b45-4422-96a5-bfaa2d788db7, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:46:59,153 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 6347bed5-5b45-4422-96a5-bfaa2d788db7, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:59,159 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 6347bed5-5b45-4422-96a5-bfaa2d788db7, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:03,331 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672158  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 20:47:03,331 - DEBUG - ydb.connection.672158  - 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 20:47:03,360 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672158  - context_resolve: Resolving endpoints for database /Root
2025-11-21 20:47:03,361 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ListEndpoints, 3f6492b5-92e4-44ab-bb46-5cc3e0d25326, localhost:22842): creating call state
2025-11-21 20:47:03,361 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ListEndpoints, 3f6492b5-92e4-44ab-bb46-5cc3e0d25326, localhost:22842): request = { database: "/Root" }
2025-11-21 20:47:03,372 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ListEndpoints, 3f6492b5-92e4-44ab-bb46-5cc3e0d25326, localhost:22842): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-frzglb4yoy.auto.internal" port: 22842 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 20:47:03,373 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672158  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-frzglb4yoy.auto.internal:22842, location 1, ssl: False>]>
2025-11-21 20:47:03,373 - DEBUG - ydb.connection.672158  - close: Closing channel for endpoint localhost:22842
2025-11-21 20:47:06,769 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:06,770 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:06,771 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 9432bbfd-4e8a-4260-8fef-f9dbff827a8b, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:06,772 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 9432bbfd-4e8a-4260-8fef-f9dbff827a8b, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:06,776 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 9432bbfd-4e8a-4260-8fef-f9dbff827a8b, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:07,704 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:07,705 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:07,706 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 1be438f5-f1bc-4f24-9dd7-b9fc4f0fba89, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:07,708 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 1be438f5-f1bc-4f24-9dd7-b9fc4f0fba89, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:07,710 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 1be438f5-f1bc-4f24-9dd7-b9fc4f0fba89, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:08,723 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:08,724 - INFO - __tests__.alter_compression.672158  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-21 20:47:08,725 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:08,726 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 29abef79-13a6-4af2-80ce-7a0152c4ccb9, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:08,727 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 29abef79-13a6-4af2-80ce-7a0152c4ccb9, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:08,729 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 29abef79-13a6-4af2-80ce-7a0152c4ccb9, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:15,596 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:15,597 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:15,598 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 5d5e8d9a-a432-47cc-958d-10f933de10ad, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:15,600 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 5d5e8d9a-a432-47cc-958d-10f933de10ad, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:15,601 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 5d5e8d9a-a432-47cc-958d-10f933de10ad, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:16,211 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:16,212 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:16,212 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 5953f7f4-10ca-4002-9227-dee66689b82f, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:16,214 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 5953f7f4-10ca-4002-9227-dee66689b82f, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:16,217 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 5953f7f4-10ca-4002-9227-dee66689b82f, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:17,167 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:17,168 - INFO - __tests__.alter_compression.672158  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-21 20:47:17,169 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:17,169 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, a7fc9ac9-8ac2-4d34-ae86-7938568f07c4, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:17,171 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, a7fc9ac9-8ac2-4d34-ae86-7938568f07c4, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:17,191 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, a7fc9ac9-8ac2-4d34-ae86-7938568f07c4, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:25,209 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:25,210 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:25,211 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 2a1ff4d6-b8aa-405d-8e0e-6fd71147e1f9, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:25,212 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 2a1ff4d6-b8aa-405d-8e0e-6fd71147e1f9, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:25,214 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 2a1ff4d6-b8aa-405d-8e0e-6fd71147e1f9, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:26,024 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:26,025 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:26,026 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 98fa328e-f1a6-4dcd-a421-4acbfd733779, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:26,027 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 98fa328e-f1a6-4dcd-a421-4acbfd733779, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:26,029 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 98fa328e-f1a6-4dcd-a421-4acbfd733779, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:26,951 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:26,952 - INFO - __tests__.alter_compression.672158  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-21 20:47:26,952 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:26,953 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, f9906d3a-a577-48d1-998e-0e6ddb669517, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:26,954 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, f9906d3a-a577-48d1-998e-0e6ddb669517, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:26,961 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, f9906d3a-a577-48d1-998e-0e6ddb669517, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:33,740 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:33,741 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:33,741 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, e6b8d363-c6eb-49be-9e46-b74257746c17, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:33,742 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, e6b8d363-c6eb-49be-9e46-b74257746c17, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:33,746 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, e6b8d363-c6eb-49be-9e46-b74257746c17, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:34,267 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:34,268 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:34,269 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, d1234f27-3074-497d-9d2b-aac1f6ce9195, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:34,270 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, d1234f27-3074-497d-9d2b-aac1f6ce9195, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:34,271 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, d1234f27-3074-497d-9d2b-aac1f6ce9195, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:35,664 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:35,665 - INFO - __tests__.alter_compression.672158  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-21 20:47:35,666 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:35,667 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 44c57240-d7f6-4d58-8fdf-b944cd746850, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:35,668 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 44c57240-d7f6-4d58-8fdf-b944cd746850, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:35,670 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 44c57240-d7f6-4d58-8fdf-b944cd746850, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:43,863 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:43,864 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:43,865 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, ded1a59b-2390-49a5-811c-d5ebaa31fe0b, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:43,865 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, ded1a59b-2390-49a5-811c-d5ebaa31fe0b, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:43,867 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, ded1a59b-2390-49a5-811c-d5ebaa31fe0b, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:44,470 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:44,471 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:44,471 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 84fb4234-9c66-4874-95e0-69f7c6034e9b, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:44,472 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 84fb4234-9c66-4874-95e0-69f7c6034e9b, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:44,474 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 84fb4234-9c66-4874-95e0-69f7c6034e9b, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:45,848 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:45,848 - INFO - __tests__.alter_compression.672158  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-21 20:47:45,849 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:45,850 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, ba111a53-b5f4-4ff2-a31b-20074c106db7, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:45,851 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, ba111a53-b5f4-4ff2-a31b-20074c106db7, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:45,871 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, ba111a53-b5f4-4ff2-a31b-20074c106db7, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:48,155 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:48,156 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:48,156 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, de5e06d9-9490-4136-a157-116e68780522, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:48,157 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, de5e06d9-9490-4136-a157-116e68780522, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:48,161 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, de5e06d9-9490-4136-a157-116e68780522, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:48,920 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:48,921 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:48,922 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, d4867d8b-30e3-47a6-bc6b-954dc087eaeb, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:48,923 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, d4867d8b-30e3-47a6-bc6b-954dc087eaeb, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:48,924 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, d4867d8b-30e3-47a6-bc6b-954dc087eaeb, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:49,477 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:49,478 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:47:49,478 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, b2b33c5a-2b74-4637-8a37-61e0c9d0f712, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:47:49,479 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, b2b33c5a-2b74-4637-8a37-61e0c9d0f712, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:49,487 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, b2b33c5a-2b74-4637-8a37-61e0c9d0f712, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:58,093 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:08,136 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:08,137 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 815657bc-6a8a-4618-ada5-46c4f414351f, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:48:08,137 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 815657bc-6a8a-4618-ada5-46c4f414351f, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:08,139 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 815657bc-6a8a-4618-ada5-46c4f414351f, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:14,929 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:14,973 - INFO - root.672158  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1595704)
2025-11-21 20:48:14,974 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:14,975 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 5b3b3f5a-7aa0-4485-9f7b-46838bf18099, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:48:14,975 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 5b3b3f5a-7aa0-4485-9f7b-46838bf18099, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:14,977 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 5b3b3f5a-7aa0-4485-9f7b-46838bf18099, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:16,087 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:16,087 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:16,088 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, c5fc329c-aab9-4bbb-aad0-1c544a291c5a, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:48:16,089 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, c5fc329c-aab9-4bbb-aad0-1c544a291c5a, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:16,090 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, c5fc329c-aab9-4bbb-aad0-1c544a291c5a, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:21,180 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:22,397 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672158  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 20:48:22,397 - DEBUG - ydb.connection.672158  - 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 20:48:22,427 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672158  - context_resolve: Resolving endpoints for database /Root
2025-11-21 20:48:22,428 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ListEndpoints, 8ef9c4c9-9b9b-4e1a-95ff-a42016e0986f, localhost:22842): creating call state
2025-11-21 20:48:22,428 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ListEndpoints, 8ef9c4c9-9b9b-4e1a-95ff-a42016e0986f, localhost:22842): request = { database: "/Root" }
2025-11-21 20:48:22,435 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ListEndpoints, 8ef9c4c9-9b9b-4e1a-95ff-a42016e0986f, localhost:22842): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-frzglb4yoy.auto.internal" port: 22842 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 20:48:22,436 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672158  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-frzglb4yoy.auto.internal:22842, location 1, ssl: False>]>
2025-11-21 20:48:22,437 - DEBUG - ydb.connection.672158  - close: Closing channel for endpoint localhost:22842
2025-11-21 20:48:31,216 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:31,218 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, a8b966e1-58c4-482e-a22b-9812fffa812f, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:48:31,218 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, a8b966e1-58c4-482e-a22b-9812fffa812f, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:31,220 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, a8b966e1-58c4-482e-a22b-9812fffa812f, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:35,023 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:45,139 - DEBUG - ydb.query.pool.672158  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:45,141 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(ExecuteQuery, 6ac9d72a-aa1e-4985-9641-602706f9a82f, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:48:45,147 - DEBUG - ydb.connection.672158  - _log_request: RpcState(ExecuteQuery, 6ac9d72a-aa1e-4985-9641-602706f9a82f, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:45,149 - DEBUG - ydb.connection.672158  - _log_response: RpcState(ExecuteQuery, 6ac9d72a-aa1e-4985-9641-602706f9a82f, ghrun-frzglb4yoy.auto.internal:22842): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:47,816 - DEBUG - ydb.query.pool.672158  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=
2025-11-21 20:48:47,829 - INFO - root.672158  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1508480)
2025-11-21 20:48:47,830 - INFO - root.672158  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_19_compression` 8092160 / 1508480: 5.364446330080611
2025-11-21 20:48:47,832 - INFO - ya.test.672158  - pytest_runtest_teardown: Test teardown
2025-11-21 20:48:47,833 - DEBUG - ydb.connection.672158  - _prepare_call: RpcState(DeleteSession, d210afc1-dd68-4281-b061-08853cef7012, ghrun-frzglb4yoy.auto.internal:22842): creating call state
2025-11-21 20:48:47,834 - DEBUG - ydb.connection.672158  - _log_request: RpcState(DeleteSession, d210afc1-dd68-4281-b061-08853cef7012, ghrun-frzglb4yoy.auto.internal:22842): request = { session_id: "ydb://session/3?node_id=1&id=MmM5YWRiN2UtODhhMmM3NDUtNTljYWI5NDEtZDQ1M2QxMWI=" }
2025-11-21 20:48:47,839 - DEBUG - ydb.connection.672158  - _log_response: RpcState(DeleteSession, d210afc1-dd68-4281-b061-08853cef7012, ghrun-frzglb4yoy.auto.internal:22842): response = { status: SUCCESS }
2025-11-21 20:48:47,841 - DEBUG - ydb.query.pool.672158  - stop: All session were deleted.
2025-11-21 20:48:47,842 - DEBUG - ydb.topic.672158  - close: Close topic client
2025-11-21 20:48:47,842 - DEBUG - ydb.topic.672158  - close: Topic client was closed
2025-11-21 20:48:47,847 - DEBUG - ydb.connection.672158  - close: Closing channel for endpoint localhost:22842
2025-11-21 20:48:47,851 - DEBUG - ydb.connection.672158  - _rpc_error_handler: RpcState(AttachSession, fd41c64a-f5f2-41dc-9048-338fa02285a9, ghrun-frzglb4yoy.auto.internal:22842): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-21 20:48:47,861 - DEBUG - ydb.connection.672158  - close: Closing channel for endpoint ghrun-frzglb4yoy.auto.internal:22842
2025-11-21 20:48:47,864 - INFO - ydb.pool.Discovery.672158  - run: Successfully terminated discovery process
2025-11-21 20:49:09,879 - INFO - ydb.tests.library.harness.kikimr_runner.672158  - stop: Stopped node localhost:22842/1
2025-11-21 20:49:09,883 - DEBUG - library.python.filelock.672158  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13482
2025-11-21 20:49:09,883 - DEBUG - library.python.filelock.672158  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5280
2025-11-21 20:49:09,884 - DEBUG - library.python.filelock.672158  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29657
2025-11-21 20:49:09,884 - DEBUG - library.python.filelock.672158  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22842
2025-11-21 20:49:09,884 - DEBUG - library.python.filelock.672158  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19028
