2025-10-15 15:14:52,139 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:14:52,139 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_5_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 5]
2025-10-15 15:14:52,140 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:14:52,140 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:14:52,143 - INFO - ya.test - 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-10-15 15:14:52,143 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:52,144 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0111ab9f-9a62-4819-b504-b272f33b7382, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:52,145 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0111ab9f-9a62-4819-b504-b272f33b7382, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:14:52,147 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0111ab9f-9a62-4819-b504-b272f33b7382, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:55,540 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:55,540 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_5_compression created
2025-10-15 15:14:55,541 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:55,541 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22d85177-2352-4c5a-92d2-9e64053e137d, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:55,542 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22d85177-2352-4c5a-92d2-9e64053e137d, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:14:55,543 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22d85177-2352-4c5a-92d2-9e64053e137d, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:56,625 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:56,626 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:56,627 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6581c2e9-cf7e-4d7b-b3ce-c42f90802a96, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:56,628 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6581c2e9-cf7e-4d7b-b3ce-c42f90802a96, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:14:56,634 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6581c2e9-cf7e-4d7b-b3ce-c42f90802a96, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:58,054 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:58,055 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:58,056 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93fdb8c8-7724-4512-8170-93803adea965, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:58,057 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93fdb8c8-7724-4512-8170-93803adea965, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:14:58,059 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93fdb8c8-7724-4512-8170-93803adea965, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:58,973 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:58,974 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:58,975 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 14781262-68e4-4185-9e42-ecff2c9128e0, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:58,976 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 14781262-68e4-4185-9e42-ecff2c9128e0, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:14:58,978 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 14781262-68e4-4185-9e42-ecff2c9128e0, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:01,025 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:01,026 - INFO - __tests__.alter_compression - 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-10-15 15:15:01,027 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:01,028 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5dcfed14-6bad-4424-b405-d5ddcd898a49, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:01,029 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5dcfed14-6bad-4424-b405-d5ddcd898a49, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:01,030 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5dcfed14-6bad-4424-b405-d5ddcd898a49, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:02,526 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:02,527 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:02,527 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0407d17d-2454-4a1e-b3dc-c6823f72e266, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:02,528 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0407d17d-2454-4a1e-b3dc-c6823f72e266, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:02,530 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0407d17d-2454-4a1e-b3dc-c6823f72e266, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:02,775 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:02,776 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:02,777 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c3041754-bd67-4e5e-ae9e-f0d7c812ee58, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:02,778 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c3041754-bd67-4e5e-ae9e-f0d7c812ee58, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:02,781 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c3041754-bd67-4e5e-ae9e-f0d7c812ee58, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:03,075 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:03,076 - INFO - __tests__.alter_compression - 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-10-15 15:15:03,076 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:03,077 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7612b449-6d70-414a-bbef-deece4de3e0c, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:03,078 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7612b449-6d70-414a-bbef-deece4de3e0c, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:03,079 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7612b449-6d70-414a-bbef-deece4de3e0c, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:04,389 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:04,389 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:04,390 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fc61034e-0c98-4aa7-a1b0-aaf944fcaeb2, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:04,391 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fc61034e-0c98-4aa7-a1b0-aaf944fcaeb2, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:04,392 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fc61034e-0c98-4aa7-a1b0-aaf944fcaeb2, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:04,785 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:04,786 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:04,787 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9cdfbe17-e1a2-4032-b2e3-0620d9270b16, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:04,788 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9cdfbe17-e1a2-4032-b2e3-0620d9270b16, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:04,789 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9cdfbe17-e1a2-4032-b2e3-0620d9270b16, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:05,282 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:05,283 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 306407, 'Portions': 185}}, blobs: {'__DEFAULT': {'Portions': 181, 'BlobSize': 958656, 'BlobCount': 384}}
2025-10-15 15:15:05,283 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:05,284 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 40b0dd46-3056-47f5-aa0a-339b0e1ccea1, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:05,284 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 40b0dd46-3056-47f5-aa0a-339b0e1ccea1, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:05,286 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 40b0dd46-3056-47f5-aa0a-339b0e1ccea1, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:06,659 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:06,659 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:06,660 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 512a2a92-d805-47e0-93f0-077de8934610, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:06,661 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 512a2a92-d805-47e0-93f0-077de8934610, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:06,662 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 512a2a92-d805-47e0-93f0-077de8934610, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:06,943 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:06,944 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:06,945 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5a7b4c3b-c658-434a-b36a-f6323f725895, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:06,946 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5a7b4c3b-c658-434a-b36a-f6323f725895, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:06,947 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5a7b4c3b-c658-434a-b36a-f6323f725895, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:07,300 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:07,301 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 239}}, blobs: {'__DEFAULT': {'Portions': 239, 'BlobSize': 1277568, 'BlobCount': 506}}
2025-10-15 15:15:07,301 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:07,302 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0d8a0bec-8f89-45b5-93da-f19f4bcc76bc, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:07,303 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0d8a0bec-8f89-45b5-93da-f19f4bcc76bc, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:07,304 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0d8a0bec-8f89-45b5-93da-f19f4bcc76bc, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:09,073 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:09,074 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:09,075 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8b030d2a-832b-44ad-b939-722eabc5de4c, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:09,076 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8b030d2a-832b-44ad-b939-722eabc5de4c, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:09,077 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8b030d2a-832b-44ad-b939-722eabc5de4c, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:09,389 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:09,390 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:09,391 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 899ae1d3-4d65-41e5-b130-b5c239937fbd, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:09,392 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 899ae1d3-4d65-41e5-b130-b5c239937fbd, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:09,394 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 899ae1d3-4d65-41e5-b130-b5c239937fbd, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:09,901 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:09,902 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 506138, 'Portions': 292}}, blobs: {'__DEFAULT': {'Portions': 288, 'BlobSize': 1594232, 'BlobCount': 614}}
2025-10-15 15:15:09,903 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:09,904 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 43e24d24-d528-447e-8630-d0eed0da245d, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:09,905 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 43e24d24-d528-447e-8630-d0eed0da245d, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:09,906 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 43e24d24-d528-447e-8630-d0eed0da245d, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:11,349 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:11,350 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:11,351 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8b5a3003-11db-4ed4-87be-21bcb8810dfd, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:11,352 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8b5a3003-11db-4ed4-87be-21bcb8810dfd, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:11,353 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8b5a3003-11db-4ed4-87be-21bcb8810dfd, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:11,657 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:11,658 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:11,659 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3236b490-c5d2-4987-91a2-b159748d6b11, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:11,659 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3236b490-c5d2-4987-91a2-b159748d6b11, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:11,660 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3236b490-c5d2-4987-91a2-b159748d6b11, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:12,658 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:12,659 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 348}}, blobs: {'__DEFAULT': {'Portions': 348, 'BlobSize': 1912568, 'BlobCount': 736}}
2025-10-15 15:15:12,660 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:12,660 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af4acdcb-a716-4094-9e15-edf4d14b9fe7, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:12,661 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af4acdcb-a716-4094-9e15-edf4d14b9fe7, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:12,663 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af4acdcb-a716-4094-9e15-edf4d14b9fe7, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:14,309 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:14,310 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:14,311 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7bf40118-f7f4-4c38-8128-6fb68643880f, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:14,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7bf40118-f7f4-4c38-8128-6fb68643880f, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:14,317 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7bf40118-f7f4-4c38-8128-6fb68643880f, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:14,672 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:14,673 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:14,674 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b75827d-cf63-4e54-bc6d-0316a30af783, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:14,674 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b75827d-cf63-4e54-bc6d-0316a30af783, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:14,676 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b75827d-cf63-4e54-bc6d-0316a30af783, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:15,219 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:15,219 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 718860, 'Portions': 399}}, blobs: {'__DEFAULT': {'Portions': 387, 'BlobSize': 2226080, 'BlobCount': 824}}
2025-10-15 15:15:15,220 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:15,221 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5bc5effd-1911-4871-bcec-d4b5d6d0d404, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:15,222 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5bc5effd-1911-4871-bcec-d4b5d6d0d404, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:15,223 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5bc5effd-1911-4871-bcec-d4b5d6d0d404, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:16,661 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:16,662 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:16,663 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c6ea7c41-e7ba-4a88-8336-0a4f51055496, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:16,664 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c6ea7c41-e7ba-4a88-8336-0a4f51055496, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:16,665 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c6ea7c41-e7ba-4a88-8336-0a4f51055496, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:16,977 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:16,978 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:16,979 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 74a3cce9-fda7-4e49-a6bb-f1c14835c407, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:16,980 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 74a3cce9-fda7-4e49-a6bb-f1c14835c407, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:16,982 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 74a3cce9-fda7-4e49-a6bb-f1c14835c407, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:17,759 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:17,760 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 811056, 'Portions': 440}}, blobs: {'__DEFAULT': {'Portions': 433, 'BlobSize': 2541136, 'BlobCount': 922}}
2025-10-15 15:15:17,760 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:17,761 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a76724ef-f5f2-4335-8743-b6635c363d48, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:17,762 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a76724ef-f5f2-4335-8743-b6635c363d48, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:17,763 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a76724ef-f5f2-4335-8743-b6635c363d48, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:19,098 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:19,099 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:19,100 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 53fe86ff-e363-4299-bded-15724dc499e1, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:19,101 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 53fe86ff-e363-4299-bded-15724dc499e1, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:19,109 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 53fe86ff-e363-4299-bded-15724dc499e1, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:19,423 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:19,424 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:19,425 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5571ab0e-c0a5-4a1f-bbfc-139d11b4a64b, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:19,426 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5571ab0e-c0a5-4a1f-bbfc-139d11b4a64b, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:19,429 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5571ab0e-c0a5-4a1f-bbfc-139d11b4a64b, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:20,250 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:20,251 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 912580, 'Portions': 491}}, blobs: {'__DEFAULT': {'Portions': 483, 'BlobSize': 2856536, 'BlobCount': 1026}}
2025-10-15 15:15:20,252 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:20,253 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 564f2aff-2dd3-40e6-aecb-92d8b59b8d45, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:20,254 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 564f2aff-2dd3-40e6-aecb-92d8b59b8d45, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:20,256 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 564f2aff-2dd3-40e6-aecb-92d8b59b8d45, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:21,693 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:21,694 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:21,695 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eba6125f-e744-436e-9f96-fd99b71a9983, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:21,696 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eba6125f-e744-436e-9f96-fd99b71a9983, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:21,698 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eba6125f-e744-436e-9f96-fd99b71a9983, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:22,092 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:22,093 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:22,094 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3f08c41d-9735-469f-a325-1be6dcaabe58, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:22,095 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3f08c41d-9735-469f-a325-1be6dcaabe58, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:22,097 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3f08c41d-9735-469f-a325-1be6dcaabe58, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:22,826 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:22,827 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 539}}, blobs: {'__DEFAULT': {'Portions': 539, 'BlobSize': 3173872, 'BlobCount': 1140}}
2025-10-15 15:15:22,828 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:22,828 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4eab1fb1-aace-4a6c-a6e5-2b92cda84090, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:22,834 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4eab1fb1-aace-4a6c-a6e5-2b92cda84090, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:22,836 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4eab1fb1-aace-4a6c-a6e5-2b92cda84090, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:24,074 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:24,075 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:24,075 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 69ffa3c0-5235-4e20-ba36-53aa96567be6, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:24,076 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 69ffa3c0-5235-4e20-ba36-53aa96567be6, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:24,077 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 69ffa3c0-5235-4e20-ba36-53aa96567be6, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:24,385 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:24,386 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:24,387 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 21d71032-7752-4c45-bc5d-c7d399c325d3, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:24,387 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 21d71032-7752-4c45-bc5d-c7d399c325d3, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:24,391 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 21d71032-7752-4c45-bc5d-c7d399c325d3, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:24,822 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:24,823 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:24,824 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08240c94-7c48-4995-8341-7c2dc76f1122, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:24,826 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08240c94-7c48-4995-8341-7c2dc76f1122, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:24,827 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08240c94-7c48-4995-8341-7c2dc76f1122, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:25,159 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:15:25,160 - DEBUG - ydb.connection - 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-10-15 15:15:25,175 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:15:25,176 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 0a38d3fd-ec69-4371-98c7-a761ff35b8a0, localhost:14078): creating call state
2025-10-15 15:15:25,176 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 0a38d3fd-ec69-4371-98c7-a761ff35b8a0, localhost:14078): request = { database: "/Root" }
2025-10-15 15:15:25,184 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 0a38d3fd-ec69-4371-98c7-a761ff35b8a0, localhost:14078): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 14078 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-10-15 15:15:25,185 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:14078, location 1, ssl: False>]>
2025-10-15 15:15:25,186 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:14078
2025-10-15 15:15:28,825 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:38,859 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:38,860 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9773d42f-a666-42fa-9a1a-6a6efc6d2e29, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:38,861 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9773d42f-a666-42fa-9a1a-6a6efc6d2e29, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:38,863 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9773d42f-a666-42fa-9a1a-6a6efc6d2e29, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:42,431 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:42,484 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1573200)
2025-10-15 15:15:42,485 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:42,486 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eeede9e7-945a-4028-b5c6-dc44b95bbc2e, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:42,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eeede9e7-945a-4028-b5c6-dc44b95bbc2e, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:42,496 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eeede9e7-945a-4028-b5c6-dc44b95bbc2e, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:42,973 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:42,974 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:42,975 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d2003bb-15ed-4c52-9719-240d4d769b85, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:42,976 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d2003bb-15ed-4c52-9719-240d4d769b85, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:42,978 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d2003bb-15ed-4c52-9719-240d4d769b85, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:48,512 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:58,585 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:15:58,586 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec2ae9ec-c27b-457f-bccd-c5dbede8f58a, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:15:58,587 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec2ae9ec-c27b-457f-bccd-c5dbede8f58a, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:15:58,589 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec2ae9ec-c27b-457f-bccd-c5dbede8f58a, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:00,782 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:16:10,810 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:16:10,811 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e9b1c1fb-5bcd-4b19-9c74-02ab57dbd31c, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:16:10,812 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e9b1c1fb-5bcd-4b19-9c74-02ab57dbd31c, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:16:10,814 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e9b1c1fb-5bcd-4b19-9c74-02ab57dbd31c, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:12,320 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:16:22,334 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:16:22,335 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7bfd6238-0c51-460b-9b7f-e6ae49479f6b, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:16:22,336 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7bfd6238-0c51-460b-9b7f-e6ae49479f6b, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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-10-15 15:16:22,338 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7bfd6238-0c51-460b-9b7f-e6ae49479f6b, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:23,674 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:16:23,689 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1506920)
2025-10-15 15:16:23,690 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_5_compression` 8082080 / 1506920: 5.363310593793964
2025-10-15 15:16:23,692 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-15 15:16:23,693 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 05075571-5cc2-441c-a5c6-accc5f00dfab, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:16:23,694 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 05075571-5cc2-441c-a5c6-accc5f00dfab, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" }
2025-10-15 15:16:23,698 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 05075571-5cc2-441c-a5c6-accc5f00dfab, ghrun-lvxbqnxafi.auto.internal:14078): response = { status: SUCCESS }
2025-10-15 15:16:23,699 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-15 15:16:23,699 - DEBUG - ydb.topic - close: Close topic client
2025-10-15 15:16:23,700 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-15 15:16:23,700 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:14078
2025-10-15 15:16:23,701 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-lvxbqnxafi.auto.internal:14078
2025-10-15 15:16:23,701 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, bb3d0de1-0be8-4b55-9b97-46ddab5b051a, ghrun-lvxbqnxafi.auto.internal:14078): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-10-15 15:16:23,705 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-15 15:16:35,721 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:14078/1
2025-10-15 15:16:35,725 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4511
2025-10-15 15:16:35,729 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12594
2025-10-15 15:16:35,730 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23533
2025-10-15 15:16:35,730 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14078
2025-10-15 15:16:35,730 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7536
