2025-12-05 16:21:13,843 - INFO - ya.test.22630  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:21:13,863 - INFO - ya.test.22630  - pytest_runtest_setup: test_all_supported_compression[zstd_9_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 9]
2025-12-05 16:21:13,874 - INFO - ya.test.22630  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:21:13,881 - INFO - ya.test.22630  - pytest_runtest_setup: Test setup
2025-12-05 16:21:13,891 - INFO - ya.test.22630  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_9_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 9])
2025-12-05 16:21:13,891 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:13,892 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 69486878-cae8-40db-9de7-a657eff82c1e, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:13,894 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 69486878-cae8-40db-9de7-a657eff82c1e, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_9_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 9)\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:13,914 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 69486878-cae8-40db-9de7-a657eff82c1e, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:16,334 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:16,356 - INFO - __tests__.alter_compression.22630  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_9_compression created
2025-12-05 16:21:16,357 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:16,358 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 39657972-86a8-4a98-8205-b54262d65110, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:16,359 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 39657972-86a8-4a98-8205-b54262d65110, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_9_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:16,372 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 39657972-86a8-4a98-8205-b54262d65110, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:16,963 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:16,964 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:16,964 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 9bca7d21-1982-4e69-9b34-5b77a447ae2f, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:16,965 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 9bca7d21-1982-4e69-9b34-5b77a447ae2f, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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,967 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 9bca7d21-1982-4e69-9b34-5b77a447ae2f, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:18,656 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:18,658 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:18,659 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 9ebc05b8-76d9-4da3-bb4d-8025a5f7b460, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:18,660 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 9ebc05b8-76d9-4da3-bb4d-8025a5f7b460, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:18,661 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 9ebc05b8-76d9-4da3-bb4d-8025a5f7b460, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:19,405 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:19,405 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:19,407 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, c90bb912-e4a3-4174-be41-5e90dd1acca9, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:19,408 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, c90bb912-e4a3-4174-be41-5e90dd1acca9, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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,409 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, c90bb912-e4a3-4174-be41-5e90dd1acca9, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:20,968 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:20,969 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318048, 'BlobCount': 128}}
2025-12-05 16:21:20,969 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:20,969 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 930c64c7-5a09-4f2b-9eda-76ddbecbacf0, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:20,970 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 930c64c7-5a09-4f2b-9eda-76ddbecbacf0, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:20,971 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 930c64c7-5a09-4f2b-9eda-76ddbecbacf0, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:22,183 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:22,184 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:22,184 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 3d61f753-dfc7-4dc4-be94-1a052fbee949, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:22,185 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 3d61f753-dfc7-4dc4-be94-1a052fbee949, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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,186 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 3d61f753-dfc7-4dc4-be94-1a052fbee949, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:22,398 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:22,399 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:22,399 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 61867b65-c921-46e4-9fb7-9154463bab69, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:22,400 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 61867b65-c921-46e4-9fb7-9154463bab69, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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,401 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 61867b65-c921-46e4-9fb7-9154463bab69, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:22,695 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:22,696 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637344, 'BlobCount': 256}}
2025-12-05 16:21:22,696 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:22,697 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 4367983e-fb1f-4149-a470-829e1101b6c6, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:22,698 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 4367983e-fb1f-4149-a470-829e1101b6c6, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:22,699 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 4367983e-fb1f-4149-a470-829e1101b6c6, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:23,788 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:23,790 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:23,791 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 577d61c0-4ee0-4e46-9616-04c4f382c573, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:23,791 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 577d61c0-4ee0-4e46-9616-04c4f382c573, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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,793 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 577d61c0-4ee0-4e46-9616-04c4f382c573, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:23,958 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:23,973 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:23,976 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, b8143def-ef92-4c97-bc65-c18f04de7de4, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:23,985 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, b8143def-ef92-4c97-bc65-c18f04de7de4, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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,000 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, b8143def-ef92-4c97-bc65-c18f04de7de4, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:24,379 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:24,380 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956384, 'BlobCount': 384}}
2025-12-05 16:21:24,380 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:24,381 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 1c5dca25-2030-4079-ba77-242d401e8d08, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:24,382 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 1c5dca25-2030-4079-ba77-242d401e8d08, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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,383 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 1c5dca25-2030-4079-ba77-242d401e8d08, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:25,594 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:25,608 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:25,614 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 09da8dd9-588c-4695-8a11-2043195c8675, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:25,623 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 09da8dd9-588c-4695-8a11-2043195c8675, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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,634 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 09da8dd9-588c-4695-8a11-2043195c8675, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:25,828 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:25,829 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:25,829 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 36ae965f-c225-4b01-a35d-b0e1e76bb28d, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:25,830 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 36ae965f-c225-4b01-a35d-b0e1e76bb28d, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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,831 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 36ae965f-c225-4b01-a35d-b0e1e76bb28d, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:26,163 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:26,173 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275744, 'BlobCount': 512}}
2025-12-05 16:21:26,187 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:26,209 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 05d6fd80-e906-4daf-8791-2b35e69f8e07, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:26,210 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 05d6fd80-e906-4daf-8791-2b35e69f8e07, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:26,211 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 05d6fd80-e906-4daf-8791-2b35e69f8e07, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:27,382 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:27,383 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:27,383 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 12036f68-3002-4173-9d85-b428f5866a6c, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:27,384 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 12036f68-3002-4173-9d85-b428f5866a6c, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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,385 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 12036f68-3002-4173-9d85-b428f5866a6c, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:27,578 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:27,579 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:27,580 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 7584ddf6-6006-41b1-9ca1-8688a1ae4aa2, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:27,581 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 7584ddf6-6006-41b1-9ca1-8688a1ae4aa2, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:27,582 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 7584ddf6-6006-41b1-9ca1-8688a1ae4aa2, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:28,044 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:28,050 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594704, 'BlobCount': 640}}
2025-12-05 16:21:28,050 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:28,051 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 8df1cd99-07e5-41e8-8462-a617d08f2e81, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:28,052 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 8df1cd99-07e5-41e8-8462-a617d08f2e81, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:28,053 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 8df1cd99-07e5-41e8-8462-a617d08f2e81, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:29,440 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:29,445 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:29,450 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 5ef6c9c1-9567-4ca1-b7e4-9c97f4b1ec56, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:29,451 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 5ef6c9c1-9567-4ca1-b7e4-9c97f4b1ec56, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:29,453 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 5ef6c9c1-9567-4ca1-b7e4-9c97f4b1ec56, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:29,664 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:29,668 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:29,669 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 8dff7d96-bb1a-498f-836e-648e7c98e698, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:29,676 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 8dff7d96-bb1a-498f-836e-648e7c98e698, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:29,678 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 8dff7d96-bb1a-498f-836e-648e7c98e698, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:30,068 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:30,069 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913888, 'BlobCount': 768}}
2025-12-05 16:21:30,069 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:30,083 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 93b65e80-5da1-4ad5-8135-ce38ab77bd90, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:30,094 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 93b65e80-5da1-4ad5-8135-ce38ab77bd90, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:30,111 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 93b65e80-5da1-4ad5-8135-ce38ab77bd90, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:31,262 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:31,263 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:31,263 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 5b430a96-eced-4982-a21c-69f1b80e9c91, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:31,264 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 5b430a96-eced-4982-a21c-69f1b80e9c91, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:31,265 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 5b430a96-eced-4982-a21c-69f1b80e9c91, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:31,478 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:31,479 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:31,480 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 9c4e1b96-f698-4463-a2f2-76d5e8d66f5c, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:31,481 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 9c4e1b96-f698-4463-a2f2-76d5e8d66f5c, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:31,482 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 9c4e1b96-f698-4463-a2f2-76d5e8d66f5c, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:31,912 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:31,912 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233024, 'BlobCount': 896}}
2025-12-05 16:21:31,913 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:31,913 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, e4ba7fe1-eb4f-4c6a-b846-0a3ee0a7af42, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:31,914 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, e4ba7fe1-eb4f-4c6a-b846-0a3ee0a7af42, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:31,974 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, e4ba7fe1-eb4f-4c6a-b846-0a3ee0a7af42, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:33,121 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:33,122 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:33,122 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, f72617a0-14fe-43ff-b47c-81441a138197, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:33,123 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, f72617a0-14fe-43ff-b47c-81441a138197, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:33,141 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, f72617a0-14fe-43ff-b47c-81441a138197, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:33,343 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:33,344 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:33,354 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, d849d732-3323-4677-bda5-a44c2cd46597, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:33,364 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, d849d732-3323-4677-bda5-a44c2cd46597, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:33,375 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, d849d732-3323-4677-bda5-a44c2cd46597, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:33,903 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:33,904 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552512, 'BlobCount': 1024}}
2025-12-05 16:21:33,905 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:33,905 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, dbe536c4-a3c8-433d-80b7-274137505491, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:33,906 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, dbe536c4-a3c8-433d-80b7-274137505491, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:33,908 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, dbe536c4-a3c8-433d-80b7-274137505491, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:34,980 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:34,992 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:34,993 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 0aea2476-510d-4ad4-bca9-40f21824979b, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:34,994 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 0aea2476-510d-4ad4-bca9-40f21824979b, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:34,995 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 0aea2476-510d-4ad4-bca9-40f21824979b, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:35,232 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:35,242 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:35,252 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 65ba5890-4863-42b1-acdf-05c37890540e, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:35,260 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 65ba5890-4863-42b1-acdf-05c37890540e, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:35,269 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 65ba5890-4863-42b1-acdf-05c37890540e, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:35,817 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:35,826 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871520, 'BlobCount': 1152}}
2025-12-05 16:21:35,826 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:35,827 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 7f10dca3-5c42-4240-8c5a-ae4709d4066d, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:35,828 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 7f10dca3-5c42-4240-8c5a-ae4709d4066d, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:35,829 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 7f10dca3-5c42-4240-8c5a-ae4709d4066d, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:36,937 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:36,937 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:36,938 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 83d00521-3906-473c-b60d-ee9be5f54ad8, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:36,939 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 83d00521-3906-473c-b60d-ee9be5f54ad8, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:36,940 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 83d00521-3906-473c-b60d-ee9be5f54ad8, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:37,195 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:37,196 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:37,197 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 033dac7b-cd00-445e-80f5-30dde598d1c7, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:37,197 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 033dac7b-cd00-445e-80f5-30dde598d1c7, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:37,199 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 033dac7b-cd00-445e-80f5-30dde598d1c7, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:37,879 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:37,888 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191024, 'BlobCount': 1280}}
2025-12-05 16:21:37,894 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:37,899 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 02c7de4c-0fee-417f-86d8-ef1be8cedffb, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:37,915 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 02c7de4c-0fee-417f-86d8-ef1be8cedffb, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_9_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:37,917 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 02c7de4c-0fee-417f-86d8-ef1be8cedffb, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:38,893 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:38,898 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:38,899 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 5257ed0a-12e4-42b9-9f67-f47c0c66cafc, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:38,900 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 5257ed0a-12e4-42b9-9f67-f47c0c66cafc, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:38,902 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 5257ed0a-12e4-42b9-9f67-f47c0c66cafc, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:39,241 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:39,253 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:39,264 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 987cac95-8ca2-487c-a0bf-bb723155c228, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:39,267 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 987cac95-8ca2-487c-a0bf-bb723155c228, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:39,276 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 987cac95-8ca2-487c-a0bf-bb723155c228, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:39,536 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:39,548 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:39,549 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, d5226ef4-7eb4-4be7-a761-eb70fdaf17f7, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:39,550 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, d5226ef4-7eb4-4be7-a761-eb70fdaf17f7, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_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:39,551 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, d5226ef4-7eb4-4be7-a761-eb70fdaf17f7, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:43,305 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:53,351 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:53,352 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 9c1c7869-4780-4b0f-ad06-b3ff2a8bb34d, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:53,353 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 9c1c7869-4780-4b0f-ad06-b3ff2a8bb34d, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_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:53,355 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 9c1c7869-4780-4b0f-ad06-b3ff2a8bb34d, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:58,091 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:58,146 - INFO - root.22630  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_9_compression`, volumes `value` (8000000, 1595512)
2025-12-05 16:21:58,147 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:58,147 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, c335130f-d824-4e39-b149-de4e4ca986a7, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:58,162 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, c335130f-d824-4e39-b149-de4e4ca986a7, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_9_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:58,174 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, c335130f-d824-4e39-b149-de4e4ca986a7, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:58,630 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:58,630 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:58,631 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, c269921e-172b-4879-8dd5-79461d394398, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:58,632 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, c269921e-172b-4879-8dd5-79461d394398, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_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:58,634 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, c269921e-172b-4879-8dd5-79461d394398, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:02,213 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:22:12,253 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:22:12,254 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 8bf6649d-6717-4ee3-8367-e717dd337fc2, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:22:12,255 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 8bf6649d-6717-4ee3-8367-e717dd337fc2, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_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:22:12,257 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 8bf6649d-6717-4ee3-8367-e717dd337fc2, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:15,120 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:22:15,181 - INFO - root.22630  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_9_compression`, volumes `value` (8000000, 1595512)
2025-12-05 16:22:15,181 - INFO - root.22630  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_9_compression` 8092160 / 1595512: 5.071826473257487
2025-12-05 16:22:15,183 - INFO - ya.test.22630  - pytest_runtest_teardown: Test teardown
2025-12-05 16:22:15,183 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(DeleteSession, e70cd923-766a-4570-893e-3e527d9acd4b, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:22:15,184 - DEBUG - ydb.connection.22630  - _log_request: RpcState(DeleteSession, e70cd923-766a-4570-893e-3e527d9acd4b, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" }
2025-12-05 16:22:15,188 - DEBUG - ydb.connection.22630  - _log_response: RpcState(DeleteSession, e70cd923-766a-4570-893e-3e527d9acd4b, ghrun-a66hhqwkt4.auto.internal:17371): response = { status: SUCCESS }
2025-12-05 16:22:15,188 - DEBUG - ydb.query.pool.22630  - stop: All session were deleted.
2025-12-05 16:22:15,189 - DEBUG - ydb.topic.22630  - close: Close topic client
2025-12-05 16:22:15,189 - DEBUG - ydb.topic.22630  - close: Topic client was closed
2025-12-05 16:22:15,189 - DEBUG - ydb.connection.22630  - close: Closing channel for endpoint localhost:17371
2025-12-05 16:22:15,189 - DEBUG - ydb.connection.22630  - close: Closing channel for endpoint ghrun-a66hhqwkt4.auto.internal:17371
2025-12-05 16:22:15,195 - INFO - ydb.pool.Discovery.22630  - run: Successfully terminated discovery process
2025-12-05 16:22:27,197 - INFO - ydb.tests.library.harness.kikimr_runner.22630  - stop: Stopped node localhost:17371/1
2025-12-05 16:22:27,222 - DEBUG - library.python.filelock.22630  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16252
2025-12-05 16:22:27,223 - DEBUG - library.python.filelock.22630  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29566
2025-12-05 16:22:27,223 - DEBUG - library.python.filelock.22630  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5150
2025-12-05 16:22:27,223 - DEBUG - library.python.filelock.22630  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17371
2025-12-05 16:22:27,224 - DEBUG - library.python.filelock.22630  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20570
