2025-11-21 20:18:19,983 - INFO - ya.test.57987  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:18:19,984 - INFO - ya.test.57987  - pytest_runtest_setup: test_all_supported_compression[zstd_5_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 5]
2025-11-21 20:18:19,984 - INFO - ya.test.57987  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:18:19,984 - INFO - ya.test.57987  - pytest_runtest_setup: Test setup
2025-11-21 20:18:19,987 - INFO - ya.test.57987  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_5_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 5])
2025-11-21 20:18:19,988 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:19,989 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, c5aee894-1853-47ba-ad63-c301266f7e95, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:19,990 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, c5aee894-1853-47ba-ad63-c301266f7e95, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_5_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 5)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:19,992 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, c5aee894-1853-47ba-ad63-c301266f7e95, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:23,290 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:23,291 - INFO - __tests__.alter_compression.57987  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_5_compression created
2025-11-21 20:18:23,292 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:23,292 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, dd2068e4-b8c5-453c-a97d-d78992d22d7c, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:23,293 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, dd2068e4-b8c5-453c-a97d-d78992d22d7c, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_5_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:23,294 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, dd2068e4-b8c5-453c-a97d-d78992d22d7c, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:24,351 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:24,351 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:24,352 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, c5a090c9-c274-4d17-bcc8-87f29e37c07c, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:24,353 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, c5a090c9-c274-4d17-bcc8-87f29e37c07c, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:24,358 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, c5a090c9-c274-4d17-bcc8-87f29e37c07c, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:25,984 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:25,985 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:25,985 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 446a84a1-27d8-4a89-a80d-20a15e18082e, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:25,986 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 446a84a1-27d8-4a89-a80d-20a15e18082e, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:25,987 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 446a84a1-27d8-4a89-a80d-20a15e18082e, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:27,528 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:27,529 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:27,530 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, cced0f97-4733-4a49-bff8-63c012d21189, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:27,531 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, cced0f97-4733-4a49-bff8-63c012d21189, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:27,532 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, cced0f97-4733-4a49-bff8-63c012d21189, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:29,610 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:29,611 - INFO - __tests__.alter_compression.57987  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318064, 'BlobCount': 128}}
2025-11-21 20:18:29,612 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:29,613 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 73cfcc61-0bc7-48d1-8c59-c318b300fc93, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:29,614 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 73cfcc61-0bc7-48d1-8c59-c318b300fc93, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:29,628 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 73cfcc61-0bc7-48d1-8c59-c318b300fc93, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:31,031 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:31,032 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:31,033 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 1199c7a8-cb97-4dec-b3a8-b4d45c96ac49, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:31,034 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 1199c7a8-cb97-4dec-b3a8-b4d45c96ac49, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:31,035 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 1199c7a8-cb97-4dec-b3a8-b4d45c96ac49, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:31,442 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:31,443 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:31,443 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 46395079-8b38-4563-8d77-4fcc19580b16, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:31,444 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 46395079-8b38-4563-8d77-4fcc19580b16, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:31,446 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 46395079-8b38-4563-8d77-4fcc19580b16, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:31,963 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:31,963 - INFO - __tests__.alter_compression.57987  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637360, 'BlobCount': 256}}
2025-11-21 20:18:31,967 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:31,968 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, f5264517-03b3-43df-97f7-b61def25b454, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:31,978 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, f5264517-03b3-43df-97f7-b61def25b454, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:31,980 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, f5264517-03b3-43df-97f7-b61def25b454, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:33,491 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:33,492 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:33,493 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 1c6d367a-3694-48bc-bed6-70835557a49e, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:33,494 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 1c6d367a-3694-48bc-bed6-70835557a49e, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:33,495 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 1c6d367a-3694-48bc-bed6-70835557a49e, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:33,798 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:33,805 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:33,806 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 0fca1ecf-3a15-405d-91f3-e3c4e30c9009, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:33,807 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 0fca1ecf-3a15-405d-91f3-e3c4e30c9009, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:33,809 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 0fca1ecf-3a15-405d-91f3-e3c4e30c9009, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:34,327 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:34,328 - INFO - __tests__.alter_compression.57987  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956400, 'BlobCount': 384}}
2025-11-21 20:18:34,329 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:34,329 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, fe8c6ba3-094a-4b13-994b-1c358eea01de, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:34,330 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, fe8c6ba3-094a-4b13-994b-1c358eea01de, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:34,332 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, fe8c6ba3-094a-4b13-994b-1c358eea01de, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:36,025 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:36,092 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:36,093 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 09036075-966e-4af1-960e-f899335e1ef4, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:36,093 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 09036075-966e-4af1-960e-f899335e1ef4, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:36,095 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 09036075-966e-4af1-960e-f899335e1ef4, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:36,667 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:36,668 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:36,669 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 0f62d825-004e-4475-b330-bf623e937614, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:36,670 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 0f62d825-004e-4475-b330-bf623e937614, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:36,673 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 0f62d825-004e-4475-b330-bf623e937614, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:37,296 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:37,296 - INFO - __tests__.alter_compression.57987  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275760, 'BlobCount': 512}}
2025-11-21 20:18:37,297 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:37,298 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 329d5ab6-c46a-4af2-9156-d01054edcfa2, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:37,299 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 329d5ab6-c46a-4af2-9156-d01054edcfa2, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:37,300 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 329d5ab6-c46a-4af2-9156-d01054edcfa2, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:38,876 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:38,877 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:38,878 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 356218b9-90cc-46c2-be65-43321ef2504c, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:38,878 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 356218b9-90cc-46c2-be65-43321ef2504c, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:38,880 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 356218b9-90cc-46c2-be65-43321ef2504c, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:39,276 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:39,277 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:39,278 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 8950114e-b2fa-4cf3-b260-b1ca21a5a415, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:39,279 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 8950114e-b2fa-4cf3-b260-b1ca21a5a415, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:39,285 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 8950114e-b2fa-4cf3-b260-b1ca21a5a415, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:39,756 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:39,757 - INFO - __tests__.alter_compression.57987  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594720, 'BlobCount': 640}}
2025-11-21 20:18:39,757 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:39,758 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 24cde612-a2a5-4cfa-9972-caffef78911c, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:39,759 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 24cde612-a2a5-4cfa-9972-caffef78911c, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:39,761 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 24cde612-a2a5-4cfa-9972-caffef78911c, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:40,934 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:40,935 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:40,935 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 7f3f90d0-6aa4-4ec4-b412-ddbedb1d0169, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:40,936 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 7f3f90d0-6aa4-4ec4-b412-ddbedb1d0169, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:40,937 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 7f3f90d0-6aa4-4ec4-b412-ddbedb1d0169, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:41,380 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:41,381 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:41,382 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, a4359b2b-0d57-47a4-b024-f9235ff6c503, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:41,383 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, a4359b2b-0d57-47a4-b024-f9235ff6c503, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:41,384 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, a4359b2b-0d57-47a4-b024-f9235ff6c503, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:42,155 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:42,156 - INFO - __tests__.alter_compression.57987  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913904, 'BlobCount': 768}}
2025-11-21 20:18:42,156 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:42,157 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 4c237a27-52f6-448a-a281-b0f1970bbe8b, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:42,158 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 4c237a27-52f6-448a-a281-b0f1970bbe8b, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:42,162 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 4c237a27-52f6-448a-a281-b0f1970bbe8b, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:43,803 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:43,804 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:43,804 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, abf28805-891a-403e-a487-b97085dfddf8, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:43,805 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, abf28805-891a-403e-a487-b97085dfddf8, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:43,806 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, abf28805-891a-403e-a487-b97085dfddf8, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:44,340 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:44,341 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:44,342 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 06d6007f-e240-41c6-8c8d-7da99d84a6de, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:44,342 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 06d6007f-e240-41c6-8c8d-7da99d84a6de, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:44,344 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 06d6007f-e240-41c6-8c8d-7da99d84a6de, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:45,320 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:45,320 - INFO - __tests__.alter_compression.57987  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233040, 'BlobCount': 896}}
2025-11-21 20:18:45,321 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:45,321 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 9e8fa364-69a7-46a3-a386-6103d909073c, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:45,322 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 9e8fa364-69a7-46a3-a386-6103d909073c, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:45,324 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 9e8fa364-69a7-46a3-a386-6103d909073c, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:47,275 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:47,276 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:47,277 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 048adc12-d171-48a5-8a2e-5c580e2013fb, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:47,277 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 048adc12-d171-48a5-8a2e-5c580e2013fb, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:47,279 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 048adc12-d171-48a5-8a2e-5c580e2013fb, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:47,655 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:47,656 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:47,657 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, cb3362f1-e35f-4a4f-9229-c52d9d58b139, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:47,658 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, cb3362f1-e35f-4a4f-9229-c52d9d58b139, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:47,659 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, cb3362f1-e35f-4a4f-9229-c52d9d58b139, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:48,465 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:48,466 - INFO - __tests__.alter_compression.57987  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552528, 'BlobCount': 1024}}
2025-11-21 20:18:48,466 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:48,467 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, c0f2b11f-eacc-4eda-b877-ddf68b5b9686, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:48,468 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, c0f2b11f-eacc-4eda-b877-ddf68b5b9686, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:48,469 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, c0f2b11f-eacc-4eda-b877-ddf68b5b9686, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:50,007 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:50,008 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:50,008 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 2a3cd2a5-b1a5-414d-8b01-9807b63d1838, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:50,009 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 2a3cd2a5-b1a5-414d-8b01-9807b63d1838, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:50,011 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 2a3cd2a5-b1a5-414d-8b01-9807b63d1838, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:50,369 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:50,370 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:50,370 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 414e2c8b-a6b4-47ad-b608-7128aaa19fcc, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:50,371 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 414e2c8b-a6b4-47ad-b608-7128aaa19fcc, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:50,372 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 414e2c8b-a6b4-47ad-b608-7128aaa19fcc, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:51,150 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:51,151 - INFO - __tests__.alter_compression.57987  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871536, 'BlobCount': 1152}}
2025-11-21 20:18:51,151 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:51,152 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, a62f1dd3-733a-4a60-a7dd-addaea2b7660, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:51,152 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, a62f1dd3-733a-4a60-a7dd-addaea2b7660, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:51,154 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, a62f1dd3-733a-4a60-a7dd-addaea2b7660, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:52,693 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:52,693 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:52,694 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 522d2b6b-ad70-4250-85db-145c9cdfb751, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:52,695 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 522d2b6b-ad70-4250-85db-145c9cdfb751, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:52,696 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 522d2b6b-ad70-4250-85db-145c9cdfb751, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:53,043 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:53,044 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:53,045 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 9e959cdb-2acf-4175-89da-e012d2a7dc91, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:53,046 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 9e959cdb-2acf-4175-89da-e012d2a7dc91, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:53,056 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 9e959cdb-2acf-4175-89da-e012d2a7dc91, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:54,139 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:54,140 - INFO - __tests__.alter_compression.57987  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191040, 'BlobCount': 1280}}
2025-11-21 20:18:54,140 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:54,141 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 72db4e85-e9ac-4dab-a146-3a22d9c05011, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:54,142 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 72db4e85-e9ac-4dab-a146-3a22d9c05011, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_5_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:54,144 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 72db4e85-e9ac-4dab-a146-3a22d9c05011, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:55,207 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:55,208 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:55,209 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, f0b9c331-0eca-4416-8ab1-63f0827ac985, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:55,210 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, f0b9c331-0eca-4416-8ab1-63f0827ac985, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:55,211 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, f0b9c331-0eca-4416-8ab1-63f0827ac985, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:55,600 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:55,601 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:55,601 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 4249e952-b940-4440-85ce-f5622795205a, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:55,602 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 4249e952-b940-4440-85ce-f5622795205a, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:55,603 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 4249e952-b940-4440-85ce-f5622795205a, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:55,920 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:55,921 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:18:55,922 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 9c3ffb81-d142-4a17-a7b1-d5b1d52e0528, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:18:55,922 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 9c3ffb81-d142-4a17-a7b1-d5b1d52e0528, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:18:55,924 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 9c3ffb81-d142-4a17-a7b1-d5b1d52e0528, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:18:59,578 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:09,613 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:09,614 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, cae448d9-6cf8-4ae1-bf4c-bf5c548f4812, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:19:09,615 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, cae448d9-6cf8-4ae1-bf4c-bf5c548f4812, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:19:09,616 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, cae448d9-6cf8-4ae1-bf4c-bf5c548f4812, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:19:13,361 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:13,425 - INFO - root.57987  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1595520)
2025-11-21 20:19:13,425 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:13,426 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, e5dda96f-3925-4603-a2f8-207261eac405, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:19:13,428 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, e5dda96f-3925-4603-a2f8-207261eac405, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" 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_5_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:19:13,429 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, e5dda96f-3925-4603-a2f8-207261eac405, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:19:13,963 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:13,964 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:13,965 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 7a1d1b20-8d4a-4db9-851f-7f6d7fb7d1a6, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:19:13,965 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 7a1d1b20-8d4a-4db9-851f-7f6d7fb7d1a6, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:19:13,971 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 7a1d1b20-8d4a-4db9-851f-7f6d7fb7d1a6, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:19:16,877 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.57987  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 20:19:16,880 - DEBUG - ydb.connection.57987  - 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:19:16,891 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.57987  - context_resolve: Resolving endpoints for database /Root
2025-11-21 20:19:16,892 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ListEndpoints, f8199e0f-a5f9-40f0-b134-46eee6f77c60, localhost:30146): creating call state
2025-11-21 20:19:16,893 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ListEndpoints, f8199e0f-a5f9-40f0-b134-46eee6f77c60, localhost:30146): request = { database: "/Root" }
2025-11-21 20:19:16,898 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ListEndpoints, f8199e0f-a5f9-40f0-b134-46eee6f77c60, localhost:30146): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-frzglb4yoy.auto.internal" port: 30146 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:19:16,899 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.57987  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-frzglb4yoy.auto.internal:30146, location 1, ssl: False>]>
2025-11-21 20:19:16,900 - DEBUG - ydb.connection.57987  - close: Closing channel for endpoint localhost:30146
2025-11-21 20:19:17,460 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:27,531 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:27,532 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, f909340b-e222-4a64-b1ed-48c758192928, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:19:27,533 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, f909340b-e222-4a64-b1ed-48c758192928, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:19:27,534 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, f909340b-e222-4a64-b1ed-48c758192928, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:19:29,172 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:39,186 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:39,187 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, a7b609f6-8d51-43f4-a41b-de63ad626c1a, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:19:39,188 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, a7b609f6-8d51-43f4-a41b-de63ad626c1a, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:19:39,189 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, a7b609f6-8d51-43f4-a41b-de63ad626c1a, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:19:39,881 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:49,885 - DEBUG - ydb.query.pool.57987  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:49,887 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(ExecuteQuery, 47107528-6cdf-4ba2-8061-d922d8c78402, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:19:49,888 - DEBUG - ydb.connection.57987  - _log_request: RpcState(ExecuteQuery, 47107528-6cdf-4ba2-8061-d922d8c78402, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:19:49,889 - DEBUG - ydb.connection.57987  - _log_response: RpcState(ExecuteQuery, 47107528-6cdf-4ba2-8061-d922d8c78402, ghrun-frzglb4yoy.auto.internal:30146): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:19:50,663 - DEBUG - ydb.query.pool.57987  - release: Session returned to queue: ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=
2025-11-21 20:19:50,711 - INFO - root.57987  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1466976)
2025-11-21 20:19:50,724 - INFO - root.57987  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_5_compression` 8092160 / 1466976: 5.51621839757433
2025-11-21 20:19:50,726 - INFO - ya.test.57987  - pytest_runtest_teardown: Test teardown
2025-11-21 20:19:50,727 - DEBUG - ydb.connection.57987  - _prepare_call: RpcState(DeleteSession, 822c2ab0-f3c8-49c5-9369-57102621f64f, ghrun-frzglb4yoy.auto.internal:30146): creating call state
2025-11-21 20:19:50,727 - DEBUG - ydb.connection.57987  - _log_request: RpcState(DeleteSession, 822c2ab0-f3c8-49c5-9369-57102621f64f, ghrun-frzglb4yoy.auto.internal:30146): request = { session_id: "ydb://session/3?node_id=1&id=MmIzMWY3ZjEtYTY5ODUzZTctZmEyMzg0ZDMtYzdmNzg1YzA=" }
2025-11-21 20:19:50,743 - DEBUG - ydb.connection.57987  - _log_response: RpcState(DeleteSession, 822c2ab0-f3c8-49c5-9369-57102621f64f, ghrun-frzglb4yoy.auto.internal:30146): response = { status: SUCCESS }
2025-11-21 20:19:50,744 - DEBUG - ydb.query.pool.57987  - stop: All session were deleted.
2025-11-21 20:19:50,744 - DEBUG - ydb.topic.57987  - close: Close topic client
2025-11-21 20:19:50,745 - DEBUG - ydb.topic.57987  - close: Topic client was closed
2025-11-21 20:19:50,745 - DEBUG - ydb.connection.57987  - close: Closing channel for endpoint localhost:30146
2025-11-21 20:19:50,746 - DEBUG - ydb.connection.57987  - close: Closing channel for endpoint ghrun-frzglb4yoy.auto.internal:30146
2025-11-21 20:19:50,756 - INFO - ydb.pool.Discovery.57987  - run: Successfully terminated discovery process
2025-11-21 20:20:12,770 - INFO - ydb.tests.library.harness.kikimr_runner.57987  - stop: Stopped node localhost:30146/1
2025-11-21 20:20:12,775 - DEBUG - library.python.filelock.57987  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25788
2025-11-21 20:20:12,775 - DEBUG - library.python.filelock.57987  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19131
2025-11-21 20:20:12,776 - DEBUG - library.python.filelock.57987  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15172
2025-11-21 20:20:12,776 - DEBUG - library.python.filelock.57987  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30146
2025-11-21 20:20:12,776 - DEBUG - library.python.filelock.57987  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8775
