2025-12-23 15:40:42,250 - INFO - ya.test.668724  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:40:42,250 - INFO - ya.test.668724  - pytest_runtest_setup: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14]
2025-12-23 15:40:42,251 - INFO - ya.test.668724  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:40:42,251 - INFO - ya.test.668724  - pytest_runtest_setup: Test setup
2025-12-23 15:40:42,253 - INFO - ya.test.668724  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14])
2025-12-23 15:40:42,254 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:42,254 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, a6fa0ccd-2683-4414-afb2-3262f80ec153, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:42,255 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, a6fa0ccd-2683-4414-afb2-3262f80ec153, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_14_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 14)\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:40:42,256 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, a6fa0ccd-2683-4414-afb2-3262f80ec153, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:45,023 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:45,024 - INFO - __tests__.alter_compression.668724  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_14_compression created
2025-12-23 15:40:45,024 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:45,025 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 24cf196f-705a-48fd-9fec-0c843788bb32, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:45,026 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 24cf196f-705a-48fd-9fec-0c843788bb32, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:40:45,027 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 24cf196f-705a-48fd-9fec-0c843788bb32, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:45,810 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:45,811 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:45,811 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 329bb3af-5ed8-4b7c-819e-b21563040a48, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:45,812 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 329bb3af-5ed8-4b7c-819e-b21563040a48, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:40:45,814 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 329bb3af-5ed8-4b7c-819e-b21563040a48, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:48,611 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:48,612 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:48,612 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 22128872-c927-42ec-8b1a-6ded00766362, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:48,613 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 22128872-c927-42ec-8b1a-6ded00766362, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:40:48,614 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 22128872-c927-42ec-8b1a-6ded00766362, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:49,596 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:49,597 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:49,597 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 234a1a02-ac40-4e6f-b753-0a7cb6ee977e, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:49,598 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 234a1a02-ac40-4e6f-b753-0a7cb6ee977e, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:40:49,600 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 234a1a02-ac40-4e6f-b753-0a7cb6ee977e, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:51,388 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:51,389 - INFO - __tests__.alter_compression.668724  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-12-23 15:40:51,389 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:51,390 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 3ec78dd6-714b-41e5-8233-3bfdb460ed9a, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:51,391 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 3ec78dd6-714b-41e5-8233-3bfdb460ed9a, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:40:51,393 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 3ec78dd6-714b-41e5-8233-3bfdb460ed9a, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:54,101 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:54,102 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:54,102 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 1a9bee4e-922d-4d69-a28f-9e1c74cbf67b, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:54,103 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 1a9bee4e-922d-4d69-a28f-9e1c74cbf67b, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:40:54,105 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 1a9bee4e-922d-4d69-a28f-9e1c74cbf67b, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:54,404 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:54,405 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:54,406 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 9c33df58-1ae0-46fc-b541-0fb0a74f57fe, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:54,407 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 9c33df58-1ae0-46fc-b541-0fb0a74f57fe, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:40:54,409 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 9c33df58-1ae0-46fc-b541-0fb0a74f57fe, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:54,771 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:54,772 - INFO - __tests__.alter_compression.668724  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-12-23 15:40:54,773 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:54,774 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, d4f5d660-5477-41ec-bba1-9f70d8841039, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:54,775 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, d4f5d660-5477-41ec-bba1-9f70d8841039, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:40:54,777 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, d4f5d660-5477-41ec-bba1-9f70d8841039, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:57,348 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:57,349 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:57,349 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, f1fa6e72-63fe-4416-89cc-b4aad28e779c, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:57,350 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, f1fa6e72-63fe-4416-89cc-b4aad28e779c, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:40:57,352 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, f1fa6e72-63fe-4416-89cc-b4aad28e779c, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:57,655 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:57,656 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:57,657 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, edbe811a-ba2c-46ab-8d02-4adf218bfd98, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:57,658 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, edbe811a-ba2c-46ab-8d02-4adf218bfd98, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:40:57,660 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, edbe811a-ba2c-46ab-8d02-4adf218bfd98, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:58,065 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:58,066 - INFO - __tests__.alter_compression.668724  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-12-23 15:40:58,066 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:40:58,067 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 3001805f-79a5-49d1-b462-5905e12c8114, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:40:58,068 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 3001805f-79a5-49d1-b462-5905e12c8114, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:40:58,070 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 3001805f-79a5-49d1-b462-5905e12c8114, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:00,687 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:00,688 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:00,689 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 3f468826-1a5e-4ed5-84d7-64dd50810d1e, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:00,690 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 3f468826-1a5e-4ed5-84d7-64dd50810d1e, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:00,692 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 3f468826-1a5e-4ed5-84d7-64dd50810d1e, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:01,045 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:01,046 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:01,046 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 82342607-43bd-4573-b656-d217a450269d, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:01,048 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 82342607-43bd-4573-b656-d217a450269d, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:01,054 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 82342607-43bd-4573-b656-d217a450269d, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:01,508 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:01,508 - INFO - __tests__.alter_compression.668724  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-12-23 15:41:01,555 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:01,556 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, dbd079ec-3e84-4236-9438-122fb72024e2, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:01,557 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, dbd079ec-3e84-4236-9438-122fb72024e2, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:01,559 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, dbd079ec-3e84-4236-9438-122fb72024e2, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:04,218 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:04,219 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:04,219 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 5a73f760-d6bc-4e9a-96fd-cc438e826452, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:04,220 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 5a73f760-d6bc-4e9a-96fd-cc438e826452, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:04,221 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 5a73f760-d6bc-4e9a-96fd-cc438e826452, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:04,493 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:04,494 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:04,494 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 959502ba-845e-4b3a-9279-0f7d7aca1b33, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:04,495 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 959502ba-845e-4b3a-9279-0f7d7aca1b33, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:04,497 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 959502ba-845e-4b3a-9279-0f7d7aca1b33, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:04,970 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:04,971 - INFO - __tests__.alter_compression.668724  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-12-23 15:41:04,971 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:04,972 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 290de48e-7598-4a65-9a1e-02afad652d24, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:04,973 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 290de48e-7598-4a65-9a1e-02afad652d24, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:04,974 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 290de48e-7598-4a65-9a1e-02afad652d24, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:07,501 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:07,502 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:07,502 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, c696e60d-5e91-4266-b8aa-b94f073f315e, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:07,503 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, c696e60d-5e91-4266-b8aa-b94f073f315e, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:07,504 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, c696e60d-5e91-4266-b8aa-b94f073f315e, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:07,801 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:07,802 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:07,803 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, cf88f0ef-a35a-4428-96f1-5e9ed875f9e9, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:07,804 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, cf88f0ef-a35a-4428-96f1-5e9ed875f9e9, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:07,805 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, cf88f0ef-a35a-4428-96f1-5e9ed875f9e9, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:08,293 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:08,293 - INFO - __tests__.alter_compression.668724  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-12-23 15:41:08,294 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:08,295 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 6f54b480-7826-48d9-b774-1fa7af729955, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:08,296 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 6f54b480-7826-48d9-b774-1fa7af729955, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:08,297 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 6f54b480-7826-48d9-b774-1fa7af729955, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:10,855 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:10,856 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:10,857 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 1a9d7db5-6c70-4aad-8420-8ff3bdcfb44b, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:10,858 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 1a9d7db5-6c70-4aad-8420-8ff3bdcfb44b, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:10,859 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 1a9d7db5-6c70-4aad-8420-8ff3bdcfb44b, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:11,098 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:11,099 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:11,100 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 87b83c56-555f-4ca2-a5bd-0ddd26727ccf, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:11,101 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 87b83c56-555f-4ca2-a5bd-0ddd26727ccf, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:11,102 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 87b83c56-555f-4ca2-a5bd-0ddd26727ccf, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:11,654 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:11,654 - INFO - __tests__.alter_compression.668724  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-12-23 15:41:11,655 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:11,655 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 51b24fab-ad4b-4ac0-8a42-0f57b022f60c, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:11,656 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 51b24fab-ad4b-4ac0-8a42-0f57b022f60c, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:11,658 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 51b24fab-ad4b-4ac0-8a42-0f57b022f60c, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:14,050 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:14,050 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:14,051 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 15196fd0-1ad6-4d24-8935-3332dbb93dd3, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:14,052 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 15196fd0-1ad6-4d24-8935-3332dbb93dd3, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:14,053 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 15196fd0-1ad6-4d24-8935-3332dbb93dd3, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:14,360 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:14,361 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:14,361 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, d0833c22-8591-40e8-bceb-8679abb356f0, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:14,362 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, d0833c22-8591-40e8-bceb-8679abb356f0, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:14,364 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, d0833c22-8591-40e8-bceb-8679abb356f0, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:14,950 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:14,951 - INFO - __tests__.alter_compression.668724  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-12-23 15:41:14,951 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:14,952 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 26af62fa-3c90-4b7f-b093-8f6badddbf2b, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:14,952 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 26af62fa-3c90-4b7f-b093-8f6badddbf2b, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:14,958 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 26af62fa-3c90-4b7f-b093-8f6badddbf2b, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:17,413 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:17,414 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:17,414 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 6f0ff4f1-d24c-42a2-a70e-dae6d77df177, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:17,415 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 6f0ff4f1-d24c-42a2-a70e-dae6d77df177, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:17,417 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 6f0ff4f1-d24c-42a2-a70e-dae6d77df177, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:17,804 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:17,805 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:17,805 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, b8e2ef95-ca96-49c4-a57e-eb53f0a97b90, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:17,806 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, b8e2ef95-ca96-49c4-a57e-eb53f0a97b90, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:17,808 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, b8e2ef95-ca96-49c4-a57e-eb53f0a97b90, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:18,473 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:18,473 - INFO - __tests__.alter_compression.668724  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-12-23 15:41:18,474 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:18,474 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, a3b10cf6-0810-49a8-8724-1458f3bcf399, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:18,475 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, a3b10cf6-0810-49a8-8724-1458f3bcf399, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:18,476 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, a3b10cf6-0810-49a8-8724-1458f3bcf399, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:21,006 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:21,006 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:21,007 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, f4736e8c-0687-4fe3-9d4a-267d7322aa51, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:21,008 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, f4736e8c-0687-4fe3-9d4a-267d7322aa51, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:21,009 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, f4736e8c-0687-4fe3-9d4a-267d7322aa51, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:21,281 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:21,282 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:21,283 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 754ba1a1-5eed-46f0-b2a1-83a93674992e, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:21,284 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 754ba1a1-5eed-46f0-b2a1-83a93674992e, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:21,286 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 754ba1a1-5eed-46f0-b2a1-83a93674992e, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:21,985 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:21,985 - INFO - __tests__.alter_compression.668724  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-12-23 15:41:21,986 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:21,986 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, e1ecc462-d45d-4a55-b220-f41e0b0ed04f, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:21,987 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, e1ecc462-d45d-4a55-b220-f41e0b0ed04f, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:41:21,988 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, e1ecc462-d45d-4a55-b220-f41e0b0ed04f, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:23,016 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:23,017 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:23,018 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, f6e53fec-8998-4106-84c0-3d6a238a7886, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:23,019 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, f6e53fec-8998-4106-84c0-3d6a238a7886, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:23,020 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, f6e53fec-8998-4106-84c0-3d6a238a7886, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:23,385 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:23,386 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:23,387 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, ba6c9b50-86fa-4f1e-ab16-7ef1dd709c30, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:23,387 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, ba6c9b50-86fa-4f1e-ab16-7ef1dd709c30, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:23,389 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, ba6c9b50-86fa-4f1e-ab16-7ef1dd709c30, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:23,638 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:23,638 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:23,639 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, c272b1e0-7570-4695-a52d-7f49efc6da04, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:23,640 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, c272b1e0-7570-4695-a52d-7f49efc6da04, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_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:41:23,641 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, c272b1e0-7570-4695-a52d-7f49efc6da04, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:27,324 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:37,400 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:37,401 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, bd17d7c8-24a8-451b-a464-71fd9c572077, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:37,402 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, bd17d7c8-24a8-451b-a464-71fd9c572077, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_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:41:37,403 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, bd17d7c8-24a8-451b-a464-71fd9c572077, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:40,617 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:40,656 - INFO - root.668724  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1596216)
2025-12-23 15:41:40,656 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:40,657 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 25a6ad0c-4c88-4184-b7c9-792ee85efc0e, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:40,658 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 25a6ad0c-4c88-4184-b7c9-792ee85efc0e, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" 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_14_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:41:40,659 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 25a6ad0c-4c88-4184-b7c9-792ee85efc0e, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:40,893 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:40,894 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:40,895 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 7954bb20-9ca5-4504-8c5a-9c68ce506503, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:40,896 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 7954bb20-9ca5-4504-8c5a-9c68ce506503, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_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:41:40,897 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 7954bb20-9ca5-4504-8c5a-9c68ce506503, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:42,082 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668724  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:41:42,083 - DEBUG - ydb.connection.668724  - 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:41:42,096 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668724  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:41:42,097 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ListEndpoints, 566a09b5-247f-4596-abb8-4b2928aa9aec, localhost:15967): creating call state
2025-12-23 15:41:42,097 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ListEndpoints, 566a09b5-247f-4596-abb8-4b2928aa9aec, localhost:15967): request = { database: "/Root" }
2025-12-23 15:41:42,112 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ListEndpoints, 566a09b5-247f-4596-abb8-4b2928aa9aec, localhost:15967): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-icmf7pzugu.auto.internal" port: 15967 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:41:42,113 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668724  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-icmf7pzugu.auto.internal:15967, location 1, ssl: False>]>
2025-12-23 15:41:42,113 - DEBUG - ydb.connection.668724  - close: Closing channel for endpoint localhost:15967
2025-12-23 15:41:43,916 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:53,956 - DEBUG - ydb.query.pool.668724  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:53,957 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(ExecuteQuery, 29475341-40ed-4565-8748-e36d070b73a3, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:53,958 - DEBUG - ydb.connection.668724  - _log_request: RpcState(ExecuteQuery, 29475341-40ed-4565-8748-e36d070b73a3, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_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:41:53,959 - DEBUG - ydb.connection.668724  - _log_response: RpcState(ExecuteQuery, 29475341-40ed-4565-8748-e36d070b73a3, ghrun-icmf7pzugu.auto.internal:15967): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:41:55,665 - DEBUG - ydb.query.pool.668724  - release: Session returned to queue: ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=
2025-12-23 15:41:55,677 - INFO - root.668724  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1785112)
2025-12-23 15:41:55,678 - INFO - root.668724  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_14_compression` 8092160 / 1785112: 4.5331385369657475
2025-12-23 15:41:55,680 - INFO - ya.test.668724  - pytest_runtest_teardown: Test teardown
2025-12-23 15:41:55,681 - DEBUG - ydb.connection.668724  - _prepare_call: RpcState(DeleteSession, f5464ea6-f940-4158-aae7-7cefbedea39d, ghrun-icmf7pzugu.auto.internal:15967): creating call state
2025-12-23 15:41:55,681 - DEBUG - ydb.connection.668724  - _log_request: RpcState(DeleteSession, f5464ea6-f940-4158-aae7-7cefbedea39d, ghrun-icmf7pzugu.auto.internal:15967): request = { session_id: "ydb://session/3?node_id=1&id=N2Q0YzlhNjMtNjEzNTYwYmQtMWVhMTlmNDUtYjYzOTk4ZTI=" }
2025-12-23 15:41:55,685 - DEBUG - ydb.connection.668724  - _log_response: RpcState(DeleteSession, f5464ea6-f940-4158-aae7-7cefbedea39d, ghrun-icmf7pzugu.auto.internal:15967): response = { status: SUCCESS }
2025-12-23 15:41:55,686 - DEBUG - ydb.query.pool.668724  - stop: All session were deleted.
2025-12-23 15:41:55,688 - DEBUG - ydb.topic.668724  - close: Close topic client
2025-12-23 15:41:55,688 - DEBUG - ydb.topic.668724  - close: Topic client was closed
2025-12-23 15:41:55,688 - DEBUG - ydb.connection.668724  - close: Closing channel for endpoint localhost:15967
2025-12-23 15:41:55,689 - DEBUG - ydb.connection.668724  - _rpc_error_handler: RpcState(AttachSession, 86381c98-13a0-4495-ba54-86ab0e3eb41b, ghrun-icmf7pzugu.auto.internal:15967): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-23 15:41:55,692 - DEBUG - ydb.connection.668724  - close: Closing channel for endpoint ghrun-icmf7pzugu.auto.internal:15967
2025-12-23 15:41:55,695 - INFO - ydb.pool.Discovery.668724  - run: Successfully terminated discovery process
2025-12-23 15:42:12,704 - INFO - ydb.tests.library.harness.kikimr_runner.668724  - stop: Stopped node localhost:15967/1
2025-12-23 15:42:12,705 - DEBUG - library.python.filelock.668724  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11004
2025-12-23 15:42:12,705 - DEBUG - library.python.filelock.668724  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13904
2025-12-23 15:42:12,706 - DEBUG - library.python.filelock.668724  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19780
2025-12-23 15:42:12,706 - DEBUG - library.python.filelock.668724  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15967
2025-12-23 15:42:12,707 - DEBUG - library.python.filelock.668724  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21069
