2025-12-07 19:48:06,264 - INFO - ya.test.30064  - pytest_runtest_setup: ####################################################################################################
2025-12-07 19:48:06,265 - INFO - ya.test.30064  - pytest_runtest_setup: test_all_supported_compression[zstd_13_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 13]
2025-12-07 19:48:06,265 - INFO - ya.test.30064  - pytest_runtest_setup: ####################################################################################################
2025-12-07 19:48:06,265 - INFO - ya.test.30064  - pytest_runtest_setup: Test setup
2025-12-07 19:48:06,272 - INFO - ya.test.30064  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_13_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 13])
2025-12-07 19:48:06,273 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:06,273 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 31e66e79-8f1d-443a-a9b9-edb0782eccb0, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:06,283 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 31e66e79-8f1d-443a-a9b9-edb0782eccb0, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_13_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 13)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:06,285 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 31e66e79-8f1d-443a-a9b9-edb0782eccb0, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:10,781 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:10,781 - INFO - __tests__.alter_compression.30064  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_13_compression created
2025-12-07 19:48:10,782 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:10,783 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 18f07bc0-0fd2-454c-9fed-cda42fffe6f5, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:10,783 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 18f07bc0-0fd2-454c-9fed-cda42fffe6f5, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_13_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:10,786 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 18f07bc0-0fd2-454c-9fed-cda42fffe6f5, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:12,511 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:12,512 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:12,513 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 38c549e2-c6a7-4ade-92c0-6fc9f076f323, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:12,514 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 38c549e2-c6a7-4ade-92c0-6fc9f076f323, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:12,516 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 38c549e2-c6a7-4ade-92c0-6fc9f076f323, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:17,101 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:17,102 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:17,102 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, bbc62531-e402-401c-ad12-713fa0ef4c9c, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:17,103 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, bbc62531-e402-401c-ad12-713fa0ef4c9c, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:17,104 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, bbc62531-e402-401c-ad12-713fa0ef4c9c, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:18,546 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:18,547 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:18,548 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 2af9ff8f-9682-489f-9e1a-01d8ba3125e5, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:18,549 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 2af9ff8f-9682-489f-9e1a-01d8ba3125e5, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:18,550 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 2af9ff8f-9682-489f-9e1a-01d8ba3125e5, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:20,571 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:20,572 - INFO - __tests__.alter_compression.30064  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-12-07 19:48:20,572 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:20,573 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 025ba2d5-c0ef-4e50-ab21-d799da703923, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:20,574 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 025ba2d5-c0ef-4e50-ab21-d799da703923, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:20,575 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 025ba2d5-c0ef-4e50-ab21-d799da703923, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:26,635 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:26,636 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:26,637 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 84a2efbf-2107-410e-be96-3f0ab7916f11, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:26,638 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 84a2efbf-2107-410e-be96-3f0ab7916f11, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:26,639 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 84a2efbf-2107-410e-be96-3f0ab7916f11, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:27,563 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:27,564 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:27,565 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 678912eb-55ca-46ed-a699-2dabf1b010d0, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:27,566 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 678912eb-55ca-46ed-a699-2dabf1b010d0, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:27,569 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 678912eb-55ca-46ed-a699-2dabf1b010d0, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:28,376 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:28,377 - INFO - __tests__.alter_compression.30064  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-12-07 19:48:28,377 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:28,378 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 60b1953f-462e-4dcb-8ccc-7ff609838985, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:28,379 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 60b1953f-462e-4dcb-8ccc-7ff609838985, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:28,387 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 60b1953f-462e-4dcb-8ccc-7ff609838985, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:32,281 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:32,282 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:32,283 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 63262cd2-ec2d-4a63-8dad-8d3d19980f38, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:32,283 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 63262cd2-ec2d-4a63-8dad-8d3d19980f38, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:32,285 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 63262cd2-ec2d-4a63-8dad-8d3d19980f38, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:32,966 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:32,967 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:32,968 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, e15d8ea4-119b-43aa-bb5a-9dbbba55deb4, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:32,969 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, e15d8ea4-119b-43aa-bb5a-9dbbba55deb4, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:32,970 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, e15d8ea4-119b-43aa-bb5a-9dbbba55deb4, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:34,159 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:34,160 - INFO - __tests__.alter_compression.30064  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-12-07 19:48:34,161 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:34,161 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 0f95814e-5e6e-4f01-a5cd-9390279c7656, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:34,164 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 0f95814e-5e6e-4f01-a5cd-9390279c7656, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:34,166 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 0f95814e-5e6e-4f01-a5cd-9390279c7656, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:39,078 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:39,196 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:39,196 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 03df7b66-3731-46cc-8741-b2f2565522ad, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:39,197 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 03df7b66-3731-46cc-8741-b2f2565522ad, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:39,199 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 03df7b66-3731-46cc-8741-b2f2565522ad, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:40,142 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:40,143 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:40,144 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 0eac2a12-32f2-4011-80bd-f9a32efd1294, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:40,145 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 0eac2a12-32f2-4011-80bd-f9a32efd1294, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:40,150 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 0eac2a12-32f2-4011-80bd-f9a32efd1294, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:41,055 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:41,056 - INFO - __tests__.alter_compression.30064  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-12-07 19:48:41,057 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:41,057 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 914a8fb2-4943-4aaa-9ec0-fd75a8b3ae2d, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:41,058 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 914a8fb2-4943-4aaa-9ec0-fd75a8b3ae2d, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:41,070 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 914a8fb2-4943-4aaa-9ec0-fd75a8b3ae2d, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:47,515 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:47,516 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:47,516 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, db00253f-dcd0-44c9-8ccd-c8cc1b283db7, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:47,517 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, db00253f-dcd0-44c9-8ccd-c8cc1b283db7, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:47,519 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, db00253f-dcd0-44c9-8ccd-c8cc1b283db7, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:48,076 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:48,077 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:48,077 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, e866f354-55f1-46ba-a8e6-d1916d048818, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:48,078 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, e866f354-55f1-46ba-a8e6-d1916d048818, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:48,080 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, e866f354-55f1-46ba-a8e6-d1916d048818, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:49,289 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:49,290 - INFO - __tests__.alter_compression.30064  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-12-07 19:48:49,291 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:49,291 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, e68da216-7cad-4940-8139-dc81b4ccc362, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:49,292 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, e68da216-7cad-4940-8139-dc81b4ccc362, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:49,294 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, e68da216-7cad-4940-8139-dc81b4ccc362, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:58,010 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:58,011 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:58,012 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 97294d65-6fed-479d-b808-e893676a17f7, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:58,013 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 97294d65-6fed-479d-b808-e893676a17f7, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:58,021 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 97294d65-6fed-479d-b808-e893676a17f7, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:58,731 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:58,731 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:48:58,732 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 1d83538a-85b0-41c9-9bcd-88c13b0b467b, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:48:58,733 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 1d83538a-85b0-41c9-9bcd-88c13b0b467b, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:58,763 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 1d83538a-85b0-41c9-9bcd-88c13b0b467b, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:00,041 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:00,042 - INFO - __tests__.alter_compression.30064  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-12-07 19:49:00,043 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:00,043 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 9410efbf-075b-4bdc-bb25-353f7dc484a9, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:00,044 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 9410efbf-075b-4bdc-bb25-353f7dc484a9, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:00,055 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 9410efbf-075b-4bdc-bb25-353f7dc484a9, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:05,863 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:05,864 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:05,865 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, a8cf4622-4431-4088-8222-e64d28eeedf6, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:05,866 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, a8cf4622-4431-4088-8222-e64d28eeedf6, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:05,874 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, a8cf4622-4431-4088-8222-e64d28eeedf6, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:06,751 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:06,752 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:06,752 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, d850ac04-d647-4bc2-90c4-c55ef5f5645f, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:06,753 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, d850ac04-d647-4bc2-90c4-c55ef5f5645f, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:06,767 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, d850ac04-d647-4bc2-90c4-c55ef5f5645f, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:08,109 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:08,109 - INFO - __tests__.alter_compression.30064  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-12-07 19:49:08,110 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:08,111 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 65748344-fe6c-4ead-b63f-caf5cd7a2f35, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:08,112 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 65748344-fe6c-4ead-b63f-caf5cd7a2f35, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:08,130 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 65748344-fe6c-4ead-b63f-caf5cd7a2f35, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:12,849 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:12,850 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:12,851 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 64ea9009-d259-4fde-8150-561b815372ed, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:12,852 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 64ea9009-d259-4fde-8150-561b815372ed, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:12,853 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 64ea9009-d259-4fde-8150-561b815372ed, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:13,167 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:13,168 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:13,169 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, c1d837df-8faf-404e-b01f-28ce477c4ced, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:13,170 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, c1d837df-8faf-404e-b01f-28ce477c4ced, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:13,171 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, c1d837df-8faf-404e-b01f-28ce477c4ced, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:13,778 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:13,783 - INFO - __tests__.alter_compression.30064  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-12-07 19:49:13,783 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:13,784 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, aa8053fb-203b-40a0-85df-a0cb8c7c22fb, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:13,785 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, aa8053fb-203b-40a0-85df-a0cb8c7c22fb, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:13,787 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, aa8053fb-203b-40a0-85df-a0cb8c7c22fb, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:18,490 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:18,491 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:18,491 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 8144f4cc-33b6-4438-9349-6bd475b2a00d, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:18,492 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 8144f4cc-33b6-4438-9349-6bd475b2a00d, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:18,496 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 8144f4cc-33b6-4438-9349-6bd475b2a00d, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:18,932 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:18,933 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:18,933 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 074186ba-07ea-427d-801b-08e775655842, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:18,934 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 074186ba-07ea-427d-801b-08e775655842, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:18,936 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 074186ba-07ea-427d-801b-08e775655842, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:20,430 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:20,431 - INFO - __tests__.alter_compression.30064  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-12-07 19:49:20,431 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:20,432 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, f95c3f28-afcc-4f5b-9774-f8457c8df4de, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:20,433 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, f95c3f28-afcc-4f5b-9774-f8457c8df4de, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:20,435 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, f95c3f28-afcc-4f5b-9774-f8457c8df4de, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:21,582 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.30064  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 19:49:21,583 - DEBUG - ydb.connection.30064  - 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-12-07 19:49:21,664 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.30064  - context_resolve: Resolving endpoints for database /Root
2025-12-07 19:49:21,665 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ListEndpoints, 69729ad3-81cb-4532-b79f-624ef30445d9, localhost:10202): creating call state
2025-12-07 19:49:21,665 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ListEndpoints, 69729ad3-81cb-4532-b79f-624ef30445d9, localhost:10202): request = { database: "/Root" }
2025-12-07 19:49:21,676 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ListEndpoints, 69729ad3-81cb-4532-b79f-624ef30445d9, localhost:10202): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7gzmsyrbqy.auto.internal" port: 10202 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-12-07 19:49:21,677 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.30064  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7gzmsyrbqy.auto.internal:10202, location 1, ssl: False>]>
2025-12-07 19:49:21,677 - DEBUG - ydb.connection.30064  - close: Closing channel for endpoint localhost:10202
2025-12-07 19:49:25,877 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:25,877 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:25,878 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, fb0bb77b-32b8-43b0-8eb8-e5a54ac23933, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:25,879 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, fb0bb77b-32b8-43b0-8eb8-e5a54ac23933, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:25,881 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, fb0bb77b-32b8-43b0-8eb8-e5a54ac23933, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:26,898 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:26,899 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:26,900 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 853d699c-5776-47ad-913c-38f22dcf59ed, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:26,901 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 853d699c-5776-47ad-913c-38f22dcf59ed, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:26,911 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 853d699c-5776-47ad-913c-38f22dcf59ed, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:28,078 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:28,146 - INFO - __tests__.alter_compression.30064  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-12-07 19:49:28,147 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:28,148 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 7a2f6a40-4414-47e5-94f1-3ee50e298d9f, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:28,149 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 7a2f6a40-4414-47e5-94f1-3ee50e298d9f, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_13_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:28,150 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 7a2f6a40-4414-47e5-94f1-3ee50e298d9f, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:29,866 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:29,867 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:29,868 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 6b4551d6-3f13-4a7b-ad30-c2c8caaa03f0, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:29,869 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 6b4551d6-3f13-4a7b-ad30-c2c8caaa03f0, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:29,870 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 6b4551d6-3f13-4a7b-ad30-c2c8caaa03f0, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:30,287 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:30,288 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:30,289 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, d5d8658c-4572-4fa6-9c34-da25b913d50d, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:30,290 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, d5d8658c-4572-4fa6-9c34-da25b913d50d, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:30,291 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, d5d8658c-4572-4fa6-9c34-da25b913d50d, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:30,581 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:30,582 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:30,583 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, a644fcda-0cc9-4e91-b9cb-0b93928fc421, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:30,584 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, a644fcda-0cc9-4e91-b9cb-0b93928fc421, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:30,585 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, a644fcda-0cc9-4e91-b9cb-0b93928fc421, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:35,747 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:45,803 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:45,804 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 86d4011d-a79a-4491-943c-4174ded1d601, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:45,805 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 86d4011d-a79a-4491-943c-4174ded1d601, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:45,810 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 86d4011d-a79a-4491-943c-4174ded1d601, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:49,975 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:50,063 - INFO - root.30064  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_13_compression`, volumes `value` (8000000, 1596216)
2025-12-07 19:49:50,063 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:50,064 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 50f80202-09c5-456c-8aa6-83b28b26a93b, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:50,065 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 50f80202-09c5-456c-8aa6-83b28b26a93b, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" 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_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:50,078 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 50f80202-09c5-456c-8aa6-83b28b26a93b, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:50,438 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:50,439 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:49:50,440 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 08ca7374-8246-430d-baaf-5048ef427307, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:49:50,441 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 08ca7374-8246-430d-baaf-5048ef427307, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:50,444 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 08ca7374-8246-430d-baaf-5048ef427307, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:54,125 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:50:04,170 - DEBUG - ydb.query.pool.30064  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:50:04,171 - DEBUG - ydb.connection.30064  - _prepare_call: RpcState(ExecuteQuery, 6c3da588-85dd-4fb4-a528-f9a9dd96a25d, ghrun-7gzmsyrbqy.auto.internal:10202): creating call state
2025-12-07 19:50:04,172 - DEBUG - ydb.connection.30064  - _log_request: RpcState(ExecuteQuery, 6c3da588-85dd-4fb4-a528-f9a9dd96a25d, ghrun-7gzmsyrbqy.auto.internal:10202): request = { session_id: "ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:50:04,187 - DEBUG - ydb.connection.30064  - _log_response: RpcState(ExecuteQuery, 6c3da588-85dd-4fb4-a528-f9a9dd96a25d, ghrun-7gzmsyrbqy.auto.internal:10202): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:50:09,650 - DEBUG - ydb.query.pool.30064  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTIwNTNhYWQtZWE3ZjdiNmUtODM0NjgzYjQtOWFhZDZmNDg=
2025-12-07 19:50:09,690 - INFO - root.30064  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_13_compression`, volumes `value` (8000000, 1596216)
2025-12-07 19:50:09,691 - INFO - root.30064  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_13_compression` 8050688 / 1596216: 5.043608133235101
2025-12-07 19:50:09,710 - INFO - ya.test.30064  - pytest_runtest_teardown: Test teardown
