2025-12-05 16:21:03,755 - INFO - ya.test.22566  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:21:03,756 - INFO - ya.test.22566  - pytest_runtest_setup: test_all_supported_compression[zstd_3_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 3]
2025-12-05 16:21:03,756 - INFO - ya.test.22566  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:21:03,782 - INFO - ya.test.22566  - pytest_runtest_setup: Test setup
2025-12-05 16:21:03,788 - INFO - ya.test.22566  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_3_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 3])
2025-12-05 16:21:03,793 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:03,820 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 5b292f5c-287f-466d-b5f3-35094a65c0fe, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:03,821 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 5b292f5c-287f-466d-b5f3-35094a65c0fe, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_3_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 3)\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-05 16:21:03,823 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 5b292f5c-287f-466d-b5f3-35094a65c0fe, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:07,520 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:07,521 - INFO - __tests__.alter_compression.22566  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_3_compression created
2025-12-05 16:21:07,522 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:07,523 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 46a8ef64-14f6-45dc-9463-d6b99a65c88d, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:07,523 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 46a8ef64-14f6-45dc-9463-d6b99a65c88d, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_3_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:07,527 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 46a8ef64-14f6-45dc-9463-d6b99a65c88d, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:08,424 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:08,433 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:08,433 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, a72aaf6d-f03f-4992-845d-3726a23da34d, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:08,434 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, a72aaf6d-f03f-4992-845d-3726a23da34d, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:08,436 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, a72aaf6d-f03f-4992-845d-3726a23da34d, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:09,524 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:09,525 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:09,526 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, b2755941-d835-4ff6-8884-b56bc2b292c1, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:09,527 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, b2755941-d835-4ff6-8884-b56bc2b292c1, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:09,530 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, b2755941-d835-4ff6-8884-b56bc2b292c1, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:10,295 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:10,298 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:10,299 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 40b0f9dc-4c53-449f-8747-27a846315ec8, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:10,306 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 40b0f9dc-4c53-449f-8747-27a846315ec8, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:10,313 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 40b0f9dc-4c53-449f-8747-27a846315ec8, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:11,928 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:11,938 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318960, 'BlobCount': 128}}
2025-12-05 16:21:11,939 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:11,939 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 6866ab8d-60a8-4403-9292-a867e0cccdb1, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:11,940 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 6866ab8d-60a8-4403-9292-a867e0cccdb1, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:11,941 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 6866ab8d-60a8-4403-9292-a867e0cccdb1, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:12,906 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:12,907 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:12,908 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, ca17fe09-1c93-421f-b892-9a7d875b5dc1, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:12,908 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, ca17fe09-1c93-421f-b892-9a7d875b5dc1, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:12,910 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, ca17fe09-1c93-421f-b892-9a7d875b5dc1, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:13,220 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:13,227 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:13,229 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 735ecd05-5e91-42b5-9677-de69eb2142a5, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:13,237 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 735ecd05-5e91-42b5-9677-de69eb2142a5, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:13,248 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 735ecd05-5e91-42b5-9677-de69eb2142a5, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:13,523 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:13,524 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638256, 'BlobCount': 256}}
2025-12-05 16:21:13,525 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:13,525 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 65157a06-dacd-4e45-8990-26a95307bd4d, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:13,526 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 65157a06-dacd-4e45-8990-26a95307bd4d, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:13,528 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 65157a06-dacd-4e45-8990-26a95307bd4d, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:14,366 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:14,367 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:14,379 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 36a01c2f-c60d-4fb4-b01c-8e6a926cd709, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:14,392 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 36a01c2f-c60d-4fb4-b01c-8e6a926cd709, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:14,403 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 36a01c2f-c60d-4fb4-b01c-8e6a926cd709, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:14,621 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:14,629 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:14,638 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, ddeeff0e-6715-487c-abf8-0e01a8a71f5d, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:14,639 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, ddeeff0e-6715-487c-abf8-0e01a8a71f5d, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:14,640 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, ddeeff0e-6715-487c-abf8-0e01a8a71f5d, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:14,949 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:14,975 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957296, 'BlobCount': 384}}
2025-12-05 16:21:15,000 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:15,001 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 59b1224f-0870-4b94-978c-fb2b74f6a9ce, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:15,002 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 59b1224f-0870-4b94-978c-fb2b74f6a9ce, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:15,004 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 59b1224f-0870-4b94-978c-fb2b74f6a9ce, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:15,969 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:15,994 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:15,995 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, b684e6f9-102b-453b-adc7-77a02fa98fd9, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:15,995 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, b684e6f9-102b-453b-adc7-77a02fa98fd9, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:15,997 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, b684e6f9-102b-453b-adc7-77a02fa98fd9, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:16,193 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:16,197 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:16,198 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 3c172d13-ec31-4d75-aec1-745d4f50224d, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:16,199 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 3c172d13-ec31-4d75-aec1-745d4f50224d, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:16,223 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 3c172d13-ec31-4d75-aec1-745d4f50224d, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:16,556 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:16,565 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276656, 'BlobCount': 512}}
2025-12-05 16:21:16,578 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:16,588 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 93539bcd-47f4-4a1b-9986-ebb7e515fa50, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:16,596 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 93539bcd-47f4-4a1b-9986-ebb7e515fa50, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:16,598 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 93539bcd-47f4-4a1b-9986-ebb7e515fa50, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:17,502 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:17,506 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:17,507 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 768a8527-6799-4016-9b29-c180a4c355a0, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:17,508 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 768a8527-6799-4016-9b29-c180a4c355a0, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:17,509 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 768a8527-6799-4016-9b29-c180a4c355a0, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:17,747 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:17,748 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:17,749 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, a2e11a80-9d82-4de5-ac5b-444632099e6c, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:17,750 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, a2e11a80-9d82-4de5-ac5b-444632099e6c, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:17,751 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, a2e11a80-9d82-4de5-ac5b-444632099e6c, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:18,167 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:18,172 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595616, 'BlobCount': 640}}
2025-12-05 16:21:18,173 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:18,174 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 0adbada0-1141-4646-934d-abe525e51270, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:18,175 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 0adbada0-1141-4646-934d-abe525e51270, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:18,176 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 0adbada0-1141-4646-934d-abe525e51270, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:19,215 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:19,216 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:19,221 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 874c1527-6c22-44c6-9a23-30eb6e2cbf79, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:19,222 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 874c1527-6c22-44c6-9a23-30eb6e2cbf79, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:19,223 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 874c1527-6c22-44c6-9a23-30eb6e2cbf79, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:19,425 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:19,426 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:19,427 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 5ae1ae69-4f2f-4d60-bd06-8c242649ce35, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:19,427 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 5ae1ae69-4f2f-4d60-bd06-8c242649ce35, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:19,428 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 5ae1ae69-4f2f-4d60-bd06-8c242649ce35, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:19,876 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:19,877 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914800, 'BlobCount': 768}}
2025-12-05 16:21:19,877 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:19,878 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 50394553-f697-48f6-9ad1-0f9a23a3e42f, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:19,879 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 50394553-f697-48f6-9ad1-0f9a23a3e42f, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:19,879 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 50394553-f697-48f6-9ad1-0f9a23a3e42f, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:20,701 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:20,705 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:20,705 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 87c62604-29b2-4acc-ac7d-b6a1c736f8c0, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:20,706 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 87c62604-29b2-4acc-ac7d-b6a1c736f8c0, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:20,708 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 87c62604-29b2-4acc-ac7d-b6a1c736f8c0, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:20,935 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:20,936 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:20,944 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, b7a4b61a-9b47-4582-851e-4d71c5b8776f, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:20,945 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, b7a4b61a-9b47-4582-851e-4d71c5b8776f, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:20,947 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, b7a4b61a-9b47-4582-851e-4d71c5b8776f, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:21,438 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:21,438 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233936, 'BlobCount': 896}}
2025-12-05 16:21:21,439 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:21,439 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 7316cd58-da9f-4a32-a194-844900da5af4, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:21,440 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 7316cd58-da9f-4a32-a194-844900da5af4, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:21,442 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 7316cd58-da9f-4a32-a194-844900da5af4, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:22,360 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:22,361 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:22,361 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 924a2c22-347f-42b1-bfab-05229de0d119, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:22,362 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 924a2c22-347f-42b1-bfab-05229de0d119, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:22,363 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 924a2c22-347f-42b1-bfab-05229de0d119, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:22,572 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:22,573 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:22,574 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 5497c71f-e856-4863-8551-b85c8638247e, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:22,575 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 5497c71f-e856-4863-8551-b85c8638247e, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:22,576 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 5497c71f-e856-4863-8551-b85c8638247e, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:23,098 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:23,101 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553424, 'BlobCount': 1024}}
2025-12-05 16:21:23,101 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:23,102 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, d91b1cd7-ce05-4f1e-b4a7-9cf7c07da7b4, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:23,102 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, d91b1cd7-ce05-4f1e-b4a7-9cf7c07da7b4, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:23,103 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, d91b1cd7-ce05-4f1e-b4a7-9cf7c07da7b4, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:23,972 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:23,973 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:23,973 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 01ed413b-dd32-4366-bee0-b91d36317e14, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:23,982 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 01ed413b-dd32-4366-bee0-b91d36317e14, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:23,994 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 01ed413b-dd32-4366-bee0-b91d36317e14, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:24,225 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:24,227 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:24,228 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 8a08eaf8-bedf-447a-8c9a-f5cde646b8d0, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:24,234 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 8a08eaf8-bedf-447a-8c9a-f5cde646b8d0, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:24,236 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 8a08eaf8-bedf-447a-8c9a-f5cde646b8d0, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:24,731 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:24,732 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872432, 'BlobCount': 1152}}
2025-12-05 16:21:24,732 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:24,732 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 2e565d9f-4bba-43de-9140-eeb79e571c1b, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:24,733 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 2e565d9f-4bba-43de-9140-eeb79e571c1b, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:24,734 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 2e565d9f-4bba-43de-9140-eeb79e571c1b, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:25,495 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:25,502 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:25,502 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 6cbc98a2-1930-4c75-9667-3f2c404f42d8, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:25,503 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 6cbc98a2-1930-4c75-9667-3f2c404f42d8, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:25,504 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 6cbc98a2-1930-4c75-9667-3f2c404f42d8, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:25,726 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:25,726 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:25,727 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 8639505c-a2b5-4b00-b5fb-2ec7d3a7f59e, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:25,728 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 8639505c-a2b5-4b00-b5fb-2ec7d3a7f59e, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:25,729 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 8639505c-a2b5-4b00-b5fb-2ec7d3a7f59e, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:26,352 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:26,354 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191936, 'BlobCount': 1280}}
2025-12-05 16:21:26,355 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:26,355 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 1a061719-8cf9-42aa-8ff9-63db2c092dc4, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:26,356 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 1a061719-8cf9-42aa-8ff9-63db2c092dc4, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_3_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:26,358 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 1a061719-8cf9-42aa-8ff9-63db2c092dc4, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:27,201 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:27,209 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:27,210 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 6c07c5c9-5ed2-467f-a99d-8a2a7f29f731, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:27,210 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 6c07c5c9-5ed2-467f-a99d-8a2a7f29f731, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:27,212 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 6c07c5c9-5ed2-467f-a99d-8a2a7f29f731, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:27,489 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:27,490 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:27,490 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 95316ba0-d23a-461c-b98b-2af1dee9990a, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:27,491 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 95316ba0-d23a-461c-b98b-2af1dee9990a, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:27,492 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 95316ba0-d23a-461c-b98b-2af1dee9990a, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:27,699 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:27,700 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:27,700 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, b2c34a60-5976-4d7a-9c72-7b5e3e6916bb, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:27,701 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, b2c34a60-5976-4d7a-9c72-7b5e3e6916bb, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_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-05 16:21:27,703 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, b2c34a60-5976-4d7a-9c72-7b5e3e6916bb, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:30,480 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:31,820 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22566  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:21:31,821 - DEBUG - ydb.connection.22566  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 16:21:31,828 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22566  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:21:31,829 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ListEndpoints, d717e8c7-b371-46ad-855e-3cb952d315a1, localhost:8243): creating call state
2025-12-05 16:21:31,829 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ListEndpoints, d717e8c7-b371-46ad-855e-3cb952d315a1, localhost:8243): request = { database: "/Root" }
2025-12-05 16:21:31,834 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ListEndpoints, d717e8c7-b371-46ad-855e-3cb952d315a1, localhost:8243): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 8243 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-05 16:21:31,835 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22566  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:8243, location 1, ssl: False>]>
2025-12-05 16:21:31,835 - DEBUG - ydb.connection.22566  - close: Closing channel for endpoint localhost:8243
2025-12-05 16:21:40,533 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:40,534 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, cab9a6f3-82f4-4f91-b720-3e20a6d32df1, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:40,535 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, cab9a6f3-82f4-4f91-b720-3e20a6d32df1, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_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-05 16:21:40,536 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, cab9a6f3-82f4-4f91-b720-3e20a6d32df1, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:43,771 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:43,811 - INFO - root.22566  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_3_compression`, volumes `value` (8000000, 1595968)
2025-12-05 16:21:43,811 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:43,812 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 1c57f547-e4f6-4af4-9700-97695106bb5c, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:43,813 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 1c57f547-e4f6-4af4-9700-97695106bb5c, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" 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_3_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-05 16:21:43,814 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 1c57f547-e4f6-4af4-9700-97695106bb5c, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:44,197 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:44,202 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:44,203 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 0b3105ea-dcdf-4d3f-9023-fc1fe83f2882, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:44,204 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 0b3105ea-dcdf-4d3f-9023-fc1fe83f2882, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_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-05 16:21:44,218 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 0b3105ea-dcdf-4d3f-9023-fc1fe83f2882, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:47,497 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:57,607 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:57,622 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 03c4477d-6752-4968-a542-082f99ebb476, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:21:57,623 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 03c4477d-6752-4968-a542-082f99ebb476, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_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-05 16:21:57,624 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 03c4477d-6752-4968-a542-082f99ebb476, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:01,337 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:22:01,390 - INFO - root.22566  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_3_compression`, volumes `value` (8000000, 1595968)
2025-12-05 16:22:01,391 - INFO - root.22566  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_3_compression` 8092160 / 1595968: 5.070377350924329
2025-12-05 16:22:01,394 - INFO - ya.test.22566  - pytest_runtest_teardown: Test teardown
2025-12-05 16:22:01,395 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(DeleteSession, 12a80f29-de83-44ac-9916-67438e4194ec, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:22:01,396 - DEBUG - ydb.connection.22566  - _log_request: RpcState(DeleteSession, 12a80f29-de83-44ac-9916-67438e4194ec, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" }
2025-12-05 16:22:01,402 - DEBUG - ydb.connection.22566  - _log_response: RpcState(DeleteSession, 12a80f29-de83-44ac-9916-67438e4194ec, ghrun-a66hhqwkt4.auto.internal:8243): response = { status: SUCCESS }
2025-12-05 16:22:01,408 - DEBUG - ydb.query.pool.22566  - stop: All session were deleted.
2025-12-05 16:22:01,409 - DEBUG - ydb.topic.22566  - close: Close topic client
2025-12-05 16:22:01,410 - DEBUG - ydb.topic.22566  - close: Topic client was closed
2025-12-05 16:22:01,410 - DEBUG - ydb.connection.22566  - close: Closing channel for endpoint localhost:8243
2025-12-05 16:22:01,412 - DEBUG - ydb.connection.22566  - close: Closing channel for endpoint ghrun-a66hhqwkt4.auto.internal:8243
2025-12-05 16:22:01,415 - INFO - ydb.pool.Discovery.22566  - run: Successfully terminated discovery process
2025-12-05 16:22:18,418 - INFO - ydb.tests.library.harness.kikimr_runner.22566  - stop: Stopped node localhost:8243/1
2025-12-05 16:22:18,441 - DEBUG - library.python.filelock.22566  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26333
2025-12-05 16:22:18,442 - DEBUG - library.python.filelock.22566  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17695
2025-12-05 16:22:18,442 - DEBUG - library.python.filelock.22566  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4860
2025-12-05 16:22:18,442 - DEBUG - library.python.filelock.22566  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8243
2025-12-05 16:22:18,443 - DEBUG - library.python.filelock.22566  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18645
