2025-12-23 15:29:27,139 - INFO - ya.test.712425  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:29:27,139 - INFO - ya.test.712425  - pytest_runtest_setup: test_all_supported_compression[zstd_5_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 5]
2025-12-23 15:29:27,140 - INFO - ya.test.712425  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:29:27,140 - INFO - ya.test.712425  - pytest_runtest_setup: Test setup
2025-12-23 15:29:27,142 - INFO - ya.test.712425  - 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-12-23 15:29:27,143 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:27,145 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 4085375a-bc62-4d9f-abf6-2b6a38a3396c, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:27,146 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 4085375a-bc62-4d9f-abf6-2b6a38a3396c, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:27,147 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 4085375a-bc62-4d9f-abf6-2b6a38a3396c, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:30,404 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:30,405 - INFO - __tests__.alter_compression.712425  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_5_compression created
2025-12-23 15:29:30,405 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:30,406 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, a8cb504f-7322-424d-bf5d-a795e4f51484, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:30,407 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, a8cb504f-7322-424d-bf5d-a795e4f51484, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:30,408 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, a8cb504f-7322-424d-bf5d-a795e4f51484, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:31,556 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:31,557 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:31,558 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 19fed758-0eb9-4adc-a70b-1ef40bc992c6, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:31,559 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 19fed758-0eb9-4adc-a70b-1ef40bc992c6, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:31,560 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 19fed758-0eb9-4adc-a70b-1ef40bc992c6, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:33,330 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:33,331 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:33,331 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, b55cd4c6-5894-4f0c-ae71-ff25261e29dd, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:33,332 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, b55cd4c6-5894-4f0c-ae71-ff25261e29dd, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:33,335 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, b55cd4c6-5894-4f0c-ae71-ff25261e29dd, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:34,463 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:34,466 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:34,466 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, b3dd7dc5-68fc-429f-b766-d3ac7e389812, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:34,468 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, b3dd7dc5-68fc-429f-b766-d3ac7e389812, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:34,471 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, b3dd7dc5-68fc-429f-b766-d3ac7e389812, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:36,520 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:36,521 - INFO - __tests__.alter_compression.712425  - 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-12-23 15:29:36,522 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:36,522 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, d48604b2-0786-4313-a86c-4f2d9dc90eaa, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:36,523 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, d48604b2-0786-4313-a86c-4f2d9dc90eaa, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:36,526 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, d48604b2-0786-4313-a86c-4f2d9dc90eaa, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:37,864 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:37,865 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:37,866 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 41ff1a56-f296-4f1c-8c66-bccf940d58a7, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:37,867 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 41ff1a56-f296-4f1c-8c66-bccf940d58a7, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:37,868 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 41ff1a56-f296-4f1c-8c66-bccf940d58a7, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:38,290 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:38,296 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:38,297 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 44b9fc9b-e962-4da7-9685-ea8beca2c210, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:38,383 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 44b9fc9b-e962-4da7-9685-ea8beca2c210, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:38,384 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 44b9fc9b-e962-4da7-9685-ea8beca2c210, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:38,907 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:38,907 - INFO - __tests__.alter_compression.712425  - 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-12-23 15:29:38,908 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:38,908 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 7163b1e1-347a-4066-a7b3-554206d952e8, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:38,909 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 7163b1e1-347a-4066-a7b3-554206d952e8, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:38,910 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 7163b1e1-347a-4066-a7b3-554206d952e8, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:40,585 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:40,586 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:40,586 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, ff424c6e-b6e1-463b-bcfa-181527c53913, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:40,600 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, ff424c6e-b6e1-463b-bcfa-181527c53913, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:40,602 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, ff424c6e-b6e1-463b-bcfa-181527c53913, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:41,408 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:41,408 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:41,415 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 38ada852-3943-4325-a5d3-eff771965f7e, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:41,416 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 38ada852-3943-4325-a5d3-eff771965f7e, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:41,424 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 38ada852-3943-4325-a5d3-eff771965f7e, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:42,103 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:42,108 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956400, 'BlobCount': 384}}
2025-12-23 15:29:42,113 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:42,113 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, ca56eff6-775a-4f99-bdbb-8441d9a72a0a, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:42,114 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, ca56eff6-775a-4f99-bdbb-8441d9a72a0a, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:42,119 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, ca56eff6-775a-4f99-bdbb-8441d9a72a0a, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:43,617 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:43,618 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:43,618 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 2f965337-a770-4dea-a5a3-cab14e270426, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:43,619 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 2f965337-a770-4dea-a5a3-cab14e270426, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:43,624 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 2f965337-a770-4dea-a5a3-cab14e270426, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:43,992 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:43,995 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:43,996 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, fb334131-5cd8-428f-b0be-563537118773, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:44,000 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, fb334131-5cd8-428f-b0be-563537118773, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:44,005 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, fb334131-5cd8-428f-b0be-563537118773, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:44,687 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:44,688 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275760, 'BlobCount': 512}}
2025-12-23 15:29:44,700 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:44,702 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, d3982767-aea0-48b9-b799-e37a50953108, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:44,704 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, d3982767-aea0-48b9-b799-e37a50953108, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:44,705 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, d3982767-aea0-48b9-b799-e37a50953108, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:46,092 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:46,093 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:46,093 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, cf289f90-b532-4006-b886-fdc3e721a417, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:46,094 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, cf289f90-b532-4006-b886-fdc3e721a417, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:46,105 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, cf289f90-b532-4006-b886-fdc3e721a417, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:46,528 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:46,529 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:46,529 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 5cdaa46d-3069-4043-bf15-684c8c6838b4, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:46,577 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 5cdaa46d-3069-4043-bf15-684c8c6838b4, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:46,595 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 5cdaa46d-3069-4043-bf15-684c8c6838b4, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:47,344 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:47,345 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594720, 'BlobCount': 640}}
2025-12-23 15:29:47,346 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:47,348 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 71dc22d5-7f0f-4943-93c2-704bb626dc89, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:47,372 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 71dc22d5-7f0f-4943-93c2-704bb626dc89, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:47,376 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 71dc22d5-7f0f-4943-93c2-704bb626dc89, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:48,739 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:48,740 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:48,741 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, e6c916a1-f649-4010-a5d6-06982556bbc1, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:48,742 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, e6c916a1-f649-4010-a5d6-06982556bbc1, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:48,743 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, e6c916a1-f649-4010-a5d6-06982556bbc1, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:49,095 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:49,096 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:49,098 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 1fd8b7c6-57e0-49be-b79c-d7a496406f00, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:49,099 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 1fd8b7c6-57e0-49be-b79c-d7a496406f00, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:49,101 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 1fd8b7c6-57e0-49be-b79c-d7a496406f00, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:49,866 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:49,876 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913904, 'BlobCount': 768}}
2025-12-23 15:29:49,876 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:49,877 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 7a404ac8-906a-4e94-ad26-0481076972f2, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:49,878 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 7a404ac8-906a-4e94-ad26-0481076972f2, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:49,880 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 7a404ac8-906a-4e94-ad26-0481076972f2, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:51,035 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:51,036 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:51,037 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, f2bd2dd6-9647-44eb-ae71-af86fd78b070, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:51,038 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, f2bd2dd6-9647-44eb-ae71-af86fd78b070, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:51,049 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, f2bd2dd6-9647-44eb-ae71-af86fd78b070, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:51,383 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:51,384 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:51,384 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, f45b8720-1559-4a83-a54d-8f1f7547cd17, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:51,385 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, f45b8720-1559-4a83-a54d-8f1f7547cd17, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:51,387 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, f45b8720-1559-4a83-a54d-8f1f7547cd17, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:52,142 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:52,148 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233040, 'BlobCount': 896}}
2025-12-23 15:29:52,148 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:52,149 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 2cbc44d4-5102-49d2-aa3f-93ad6eef8f50, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:52,150 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 2cbc44d4-5102-49d2-aa3f-93ad6eef8f50, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:52,151 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 2cbc44d4-5102-49d2-aa3f-93ad6eef8f50, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:53,468 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:53,479 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:53,480 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 7018fd8a-c9f7-4c1f-9d7f-4f74a91333b6, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:53,481 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 7018fd8a-c9f7-4c1f-9d7f-4f74a91333b6, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:53,482 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 7018fd8a-c9f7-4c1f-9d7f-4f74a91333b6, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:53,889 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:53,890 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:53,891 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 328508c9-a38b-450e-9047-9d03288c916f, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:53,900 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 328508c9-a38b-450e-9047-9d03288c916f, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:53,902 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 328508c9-a38b-450e-9047-9d03288c916f, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:54,716 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:54,717 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552528, 'BlobCount': 1024}}
2025-12-23 15:29:54,717 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:54,718 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 7a03afa8-0f32-4109-b8a5-7f83cc56723f, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:54,719 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 7a03afa8-0f32-4109-b8a5-7f83cc56723f, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:54,723 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 7a03afa8-0f32-4109-b8a5-7f83cc56723f, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:56,431 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:56,432 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:56,440 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 8fbc354d-c317-49cd-af05-d4a2d2e4a4e8, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:56,456 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 8fbc354d-c317-49cd-af05-d4a2d2e4a4e8, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:56,458 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 8fbc354d-c317-49cd-af05-d4a2d2e4a4e8, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:56,858 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:56,860 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:56,862 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, b2ad8c07-1951-4edb-b17c-147ef1ea7b3e, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:56,868 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, b2ad8c07-1951-4edb-b17c-147ef1ea7b3e, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:56,870 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, b2ad8c07-1951-4edb-b17c-147ef1ea7b3e, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:57,987 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:57,988 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871536, 'BlobCount': 1152}}
2025-12-23 15:29:57,989 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:57,989 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, d1946260-bda2-4966-ad7c-0c037fd928f7, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:58,002 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, d1946260-bda2-4966-ad7c-0c037fd928f7, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:58,061 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, d1946260-bda2-4966-ad7c-0c037fd928f7, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:59,822 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:59,823 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:59,824 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, fae63b7a-7119-49d2-808d-e51fa035c834, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:59,825 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, fae63b7a-7119-49d2-808d-e51fa035c834, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:59,829 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, fae63b7a-7119-49d2-808d-e51fa035c834, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:00,284 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:00,284 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:00,285 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, db581d13-8431-48b9-b5d9-12c0569c0d20, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:30:00,286 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, db581d13-8431-48b9-b5d9-12c0569c0d20, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:00,288 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, db581d13-8431-48b9-b5d9-12c0569c0d20, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:01,333 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:01,334 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191040, 'BlobCount': 1280}}
2025-12-23 15:30:01,335 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:01,336 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 609afc61-8a68-4422-8c9e-5074e01974bc, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:30:01,336 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 609afc61-8a68-4422-8c9e-5074e01974bc, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:01,389 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 609afc61-8a68-4422-8c9e-5074e01974bc, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:01,871 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.712425  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:30:01,908 - DEBUG - ydb.connection.712425  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:30:01,934 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.712425  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:30:01,968 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ListEndpoints, db3dfe49-cb59-4e00-9edb-ec6591dba681, localhost:5471): creating call state
2025-12-23 15:30:01,970 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ListEndpoints, db3dfe49-cb59-4e00-9edb-ec6591dba681, localhost:5471): request = { database: "/Root" }
2025-12-23 15:30:02,013 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ListEndpoints, db3dfe49-cb59-4e00-9edb-ec6591dba681, localhost:5471): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-hclevy2dda.auto.internal" port: 5471 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-23 15:30:02,014 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.712425  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-hclevy2dda.auto.internal:5471, location 1, ssl: False>]>
2025-12-23 15:30:02,015 - DEBUG - ydb.connection.712425  - close: Closing channel for endpoint localhost:5471
2025-12-23 15:30:02,991 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:02,992 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:02,993 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 36a359c2-686d-4da0-aff3-5a6bc211c1b5, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:30:03,006 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 36a359c2-686d-4da0-aff3-5a6bc211c1b5, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:03,008 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 36a359c2-686d-4da0-aff3-5a6bc211c1b5, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:03,470 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:03,471 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:03,471 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, e1841f2c-667a-4952-a05d-22ccdabf034d, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:30:03,472 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, e1841f2c-667a-4952-a05d-22ccdabf034d, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:03,474 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, e1841f2c-667a-4952-a05d-22ccdabf034d, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:04,018 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:04,019 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:04,021 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 0fa82260-4739-4c3a-8749-e523c91c7e7b, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:30:04,024 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 0fa82260-4739-4c3a-8749-e523c91c7e7b, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:04,025 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 0fa82260-4739-4c3a-8749-e523c91c7e7b, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:09,034 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:19,128 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:19,181 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, dfe33c3c-9a1b-46ec-ad3c-7ede63fd28ce, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:30:19,214 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, dfe33c3c-9a1b-46ec-ad3c-7ede63fd28ce, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:19,220 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, dfe33c3c-9a1b-46ec-ad3c-7ede63fd28ce, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:24,526 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:24,587 - INFO - root.712425  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1595520)
2025-12-23 15:30:24,588 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:24,589 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 5d218f1c-1d12-4777-b324-1c16e0d83500, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:30:24,590 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 5d218f1c-1d12-4777-b324-1c16e0d83500, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:24,594 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 5d218f1c-1d12-4777-b324-1c16e0d83500, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:25,103 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:25,104 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:25,112 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, f4675fac-7361-40df-91f0-5eb7232ec118, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:30:25,116 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, f4675fac-7361-40df-91f0-5eb7232ec118, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:25,117 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, f4675fac-7361-40df-91f0-5eb7232ec118, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:28,800 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:38,835 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:38,836 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 191ef051-ad1b-490e-9ecb-022f996582e0, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:30:38,837 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 191ef051-ad1b-490e-9ecb-022f996582e0, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:38,839 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 191ef051-ad1b-490e-9ecb-022f996582e0, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:39,619 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:30:39,624 - INFO - root.712425  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1466976)
2025-12-23 15:30:39,627 - INFO - root.712425  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_5_compression` 8092160 / 1466976: 5.51621839757433
2025-12-23 15:30:39,632 - INFO - ya.test.712425  - pytest_runtest_teardown: Test teardown
2025-12-23 15:30:39,633 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(DeleteSession, 4f8347c0-8a39-486e-8690-414d8dcc72d8, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:30:39,634 - DEBUG - ydb.connection.712425  - _log_request: RpcState(DeleteSession, 4f8347c0-8a39-486e-8690-414d8dcc72d8, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" }
2025-12-23 15:30:39,638 - DEBUG - ydb.connection.712425  - _log_response: RpcState(DeleteSession, 4f8347c0-8a39-486e-8690-414d8dcc72d8, ghrun-hclevy2dda.auto.internal:5471): response = { status: SUCCESS }
2025-12-23 15:30:39,644 - DEBUG - ydb.query.pool.712425  - stop: All session were deleted.
2025-12-23 15:30:39,644 - DEBUG - ydb.topic.712425  - close: Close topic client
2025-12-23 15:30:39,645 - DEBUG - ydb.topic.712425  - close: Topic client was closed
2025-12-23 15:30:39,645 - DEBUG - ydb.connection.712425  - close: Closing channel for endpoint localhost:5471
2025-12-23 15:30:39,651 - DEBUG - ydb.connection.712425  - close: Closing channel for endpoint ghrun-hclevy2dda.auto.internal:5471
2025-12-23 15:30:39,662 - INFO - ydb.pool.Discovery.712425  - run: Successfully terminated discovery process
2025-12-23 15:30:56,671 - INFO - ydb.tests.library.harness.kikimr_runner.712425  - stop: Stopped node localhost:5471/1
2025-12-23 15:30:56,698 - DEBUG - library.python.filelock.712425  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11616
2025-12-23 15:30:56,699 - DEBUG - library.python.filelock.712425  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2793
2025-12-23 15:30:56,699 - DEBUG - library.python.filelock.712425  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31409
2025-12-23 15:30:56,706 - DEBUG - library.python.filelock.712425  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5471
2025-12-23 15:30:56,730 - DEBUG - library.python.filelock.712425  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63596
