2025-12-05 17:09:59,927 - INFO - ya.test.760156  - pytest_runtest_setup: ####################################################################################################
2025-12-05 17:09:59,928 - INFO - ya.test.760156  - pytest_runtest_setup: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19]
2025-12-05 17:09:59,930 - INFO - ya.test.760156  - pytest_runtest_setup: ####################################################################################################
2025-12-05 17:09:59,934 - INFO - ya.test.760156  - pytest_runtest_setup: Test setup
2025-12-05 17:09:59,936 - INFO - ya.test.760156  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19])
2025-12-05 17:09:59,937 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:09:59,938 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, bc94922a-befc-4214-a54a-b7e55e6de273, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:09:59,939 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, bc94922a-befc-4214-a54a-b7e55e6de273, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_19_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 19)\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 17:09:59,940 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, bc94922a-befc-4214-a54a-b7e55e6de273, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:05,686 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:05,687 - INFO - __tests__.alter_compression.760156  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_19_compression created
2025-12-05 17:10:05,689 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:05,693 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 80d355ee-a918-46ca-86ad-87b12db6dea7, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:05,702 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 80d355ee-a918-46ca-86ad-87b12db6dea7, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:10:05,704 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 80d355ee-a918-46ca-86ad-87b12db6dea7, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:06,919 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:06,923 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:06,924 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 390fad37-34e7-48ae-82d1-976065792865, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:06,924 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 390fad37-34e7-48ae-82d1-976065792865, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:06,927 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 390fad37-34e7-48ae-82d1-976065792865, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:12,920 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:12,920 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:12,921 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 2f11bc43-6ccb-4f82-b666-b9f0c29b5b58, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:12,922 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 2f11bc43-6ccb-4f82-b666-b9f0c29b5b58, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:12,923 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 2f11bc43-6ccb-4f82-b666-b9f0c29b5b58, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:14,218 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:14,224 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:14,235 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 3f87049b-cd6c-4bfb-837b-8e76b393860e, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:14,237 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 3f87049b-cd6c-4bfb-837b-8e76b393860e, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:14,240 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 3f87049b-cd6c-4bfb-837b-8e76b393860e, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:17,256 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:17,257 - INFO - __tests__.alter_compression.760156  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-12-05 17:10:17,263 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:17,263 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, e2970f1e-2c13-493a-b9bd-cba8497f4027, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:17,269 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, e2970f1e-2c13-493a-b9bd-cba8497f4027, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:17,275 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, e2970f1e-2c13-493a-b9bd-cba8497f4027, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:23,474 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:23,490 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:23,491 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 6c7e83e2-bfe3-48c7-9ef0-766627b3e11f, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:23,491 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 6c7e83e2-bfe3-48c7-9ef0-766627b3e11f, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:23,493 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 6c7e83e2-bfe3-48c7-9ef0-766627b3e11f, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:24,006 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:24,006 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:24,007 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 1b7f67d1-9839-4511-abfa-89e94133a58f, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:24,008 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 1b7f67d1-9839-4511-abfa-89e94133a58f, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:24,010 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 1b7f67d1-9839-4511-abfa-89e94133a58f, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:24,808 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:24,815 - INFO - __tests__.alter_compression.760156  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-12-05 17:10:24,816 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:24,816 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 8c725a30-8b63-413f-8aca-bfa3a066437d, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:24,817 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 8c725a30-8b63-413f-8aca-bfa3a066437d, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:24,819 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 8c725a30-8b63-413f-8aca-bfa3a066437d, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:29,573 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:29,574 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:29,575 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 09aa7132-145f-4f09-85c6-5d724fb3bdc8, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:29,580 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 09aa7132-145f-4f09-85c6-5d724fb3bdc8, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:29,582 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 09aa7132-145f-4f09-85c6-5d724fb3bdc8, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:29,991 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:29,993 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:29,994 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, c19c637f-1a0a-48af-a4b2-a53000633cb6, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:29,995 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, c19c637f-1a0a-48af-a4b2-a53000633cb6, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:29,996 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, c19c637f-1a0a-48af-a4b2-a53000633cb6, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:30,489 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:30,490 - INFO - __tests__.alter_compression.760156  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-12-05 17:10:30,493 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:30,493 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 894fcfab-a52f-4235-9e0d-e47185f72c51, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:30,495 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 894fcfab-a52f-4235-9e0d-e47185f72c51, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:30,498 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 894fcfab-a52f-4235-9e0d-e47185f72c51, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:34,709 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:34,722 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:34,722 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 696b39d0-ac66-40a4-9892-638950dbe237, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:34,734 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 696b39d0-ac66-40a4-9892-638950dbe237, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:34,759 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 696b39d0-ac66-40a4-9892-638950dbe237, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:35,076 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:35,077 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:35,077 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 3c612f82-e3c1-421d-bd21-328f0a9c18b0, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:35,078 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 3c612f82-e3c1-421d-bd21-328f0a9c18b0, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:35,080 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 3c612f82-e3c1-421d-bd21-328f0a9c18b0, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:35,633 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:35,634 - INFO - __tests__.alter_compression.760156  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-12-05 17:10:35,635 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:35,635 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, c32773b1-2f4d-4296-8df8-c9511db95d8f, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:35,636 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, c32773b1-2f4d-4296-8df8-c9511db95d8f, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:35,638 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, c32773b1-2f4d-4296-8df8-c9511db95d8f, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:40,408 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:40,410 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:40,411 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 41fc1c61-a18d-4a28-87c0-d68f4beb0956, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:40,412 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 41fc1c61-a18d-4a28-87c0-d68f4beb0956, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:40,414 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 41fc1c61-a18d-4a28-87c0-d68f4beb0956, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:40,786 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:40,787 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:40,787 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, d51c4319-3a65-449b-b092-4b9cc7c36906, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:40,794 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, d51c4319-3a65-449b-b092-4b9cc7c36906, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:40,796 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, d51c4319-3a65-449b-b092-4b9cc7c36906, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:41,357 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:41,358 - INFO - __tests__.alter_compression.760156  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-12-05 17:10:41,358 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:41,359 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, fe8e5245-38e9-447d-bd72-93df9503dcb7, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:41,360 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, fe8e5245-38e9-447d-bd72-93df9503dcb7, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:41,362 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, fe8e5245-38e9-447d-bd72-93df9503dcb7, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:45,961 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:45,962 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:45,966 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 688d2aeb-4510-49e3-b3e9-cc935a2ef262, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:45,967 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 688d2aeb-4510-49e3-b3e9-cc935a2ef262, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:45,968 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 688d2aeb-4510-49e3-b3e9-cc935a2ef262, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:46,314 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:46,316 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:46,317 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, af9ab60f-99a9-4f4e-90be-b8ac179f407f, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:46,318 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, af9ab60f-99a9-4f4e-90be-b8ac179f407f, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:46,320 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, af9ab60f-99a9-4f4e-90be-b8ac179f407f, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:46,947 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:46,948 - INFO - __tests__.alter_compression.760156  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-12-05 17:10:46,948 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:46,949 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 337a085f-4646-46fd-b8f8-f8829154e0fd, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:46,950 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 337a085f-4646-46fd-b8f8-f8829154e0fd, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:46,951 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 337a085f-4646-46fd-b8f8-f8829154e0fd, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:51,677 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:51,678 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:51,679 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 127760e4-1b8e-4574-b1a1-6a08e600640f, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:51,680 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 127760e4-1b8e-4574-b1a1-6a08e600640f, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:51,682 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 127760e4-1b8e-4574-b1a1-6a08e600640f, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:52,069 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:52,070 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:52,071 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 6aa7cfd8-3a78-468b-8248-205119f348da, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:52,072 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 6aa7cfd8-3a78-468b-8248-205119f348da, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:52,076 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 6aa7cfd8-3a78-468b-8248-205119f348da, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:52,770 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:52,771 - INFO - __tests__.alter_compression.760156  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-12-05 17:10:52,772 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:52,773 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, e22c57c0-0890-45fb-9912-0787264ec759, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:52,774 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, e22c57c0-0890-45fb-9912-0787264ec759, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:52,775 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, e22c57c0-0890-45fb-9912-0787264ec759, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:56,742 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:56,742 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:56,743 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, f71da091-f88e-4a50-97c2-8bea0f9754c2, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:56,744 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, f71da091-f88e-4a50-97c2-8bea0f9754c2, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:56,789 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, f71da091-f88e-4a50-97c2-8bea0f9754c2, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:57,113 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:57,115 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:57,116 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 732c48e5-f279-49fb-a393-9f3a2d7eaba1, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:57,118 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 732c48e5-f279-49fb-a393-9f3a2d7eaba1, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:57,119 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 732c48e5-f279-49fb-a393-9f3a2d7eaba1, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:10:57,818 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:57,818 - INFO - __tests__.alter_compression.760156  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-12-05 17:10:57,818 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:10:57,819 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 0126b695-7e46-4d66-b1c9-aa5b395c1c94, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:10:57,820 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 0126b695-7e46-4d66-b1c9-aa5b395c1c94, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:10:57,821 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 0126b695-7e46-4d66-b1c9-aa5b395c1c94, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:02,203 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:02,211 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:02,212 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, e1235125-4a45-4094-8c0b-ad6871c1c3af, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:02,213 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, e1235125-4a45-4094-8c0b-ad6871c1c3af, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:11:02,216 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, e1235125-4a45-4094-8c0b-ad6871c1c3af, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:02,538 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:02,544 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:02,545 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, ac447a1f-444e-491e-9bcb-9b9a3654cddc, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:02,546 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, ac447a1f-444e-491e-9bcb-9b9a3654cddc, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:11:02,555 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, ac447a1f-444e-491e-9bcb-9b9a3654cddc, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:03,371 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:03,371 - INFO - __tests__.alter_compression.760156  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-12-05 17:11:03,372 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:03,376 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 8f7fcd1d-19f3-47bf-9e60-6b8a4604e8b2, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:03,380 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 8f7fcd1d-19f3-47bf-9e60-6b8a4604e8b2, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:11:03,382 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 8f7fcd1d-19f3-47bf-9e60-6b8a4604e8b2, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:07,817 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:07,818 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:07,819 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, a0d4c16d-4362-49e2-8066-7a8350191972, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:07,820 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, a0d4c16d-4362-49e2-8066-7a8350191972, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:11:07,823 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, a0d4c16d-4362-49e2-8066-7a8350191972, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:08,098 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:08,099 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:08,099 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, d636454a-73be-4ac1-b2cf-4d70c6ec841d, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:08,100 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, d636454a-73be-4ac1-b2cf-4d70c6ec841d, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:11:08,102 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, d636454a-73be-4ac1-b2cf-4d70c6ec841d, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:08,860 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:08,869 - INFO - __tests__.alter_compression.760156  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-12-05 17:11:08,871 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:08,871 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, ecfad371-4d2b-450b-8fb3-a44bcccf5478, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:08,872 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, ecfad371-4d2b-450b-8fb3-a44bcccf5478, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 17:11:08,873 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, ecfad371-4d2b-450b-8fb3-a44bcccf5478, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:10,021 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:10,022 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:10,022 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 531c6cbf-6971-4e43-962c-347c4950a091, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:10,023 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 531c6cbf-6971-4e43-962c-347c4950a091, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:11:10,026 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 531c6cbf-6971-4e43-962c-347c4950a091, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:10,425 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:10,426 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:10,426 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 721149fe-a3a5-4711-bc1d-3b5f950991ba, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:10,427 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 721149fe-a3a5-4711-bc1d-3b5f950991ba, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:11:10,428 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 721149fe-a3a5-4711-bc1d-3b5f950991ba, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:10,734 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:10,735 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:10,736 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, b2a17f9a-d3ef-4157-822b-99ed063bb0a2, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:10,737 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, b2a17f9a-d3ef-4157-822b-99ed063bb0a2, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_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 17:11:10,738 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, b2a17f9a-d3ef-4157-822b-99ed063bb0a2, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:15,095 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:15,833 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.760156  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 17:11:15,834 - DEBUG - ydb.connection.760156  - 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 17:11:15,872 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.760156  - context_resolve: Resolving endpoints for database /Root
2025-12-05 17:11:15,874 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ListEndpoints, 5d484ccb-5f5b-4e43-9ca1-6455b19249c0, localhost:8294): creating call state
2025-12-05 17:11:15,875 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ListEndpoints, 5d484ccb-5f5b-4e43-9ca1-6455b19249c0, localhost:8294): request = { database: "/Root" }
2025-12-05 17:11:15,881 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ListEndpoints, 5d484ccb-5f5b-4e43-9ca1-6455b19249c0, localhost:8294): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 8294 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 17:11:15,882 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.760156  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:8294, location 1, ssl: False>]>
2025-12-05 17:11:15,882 - DEBUG - ydb.connection.760156  - close: Closing channel for endpoint localhost:8294
2025-12-05 17:11:25,129 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:25,131 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, 18633e67-df47-41a4-8ac4-eab9f95820d2, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:25,137 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, 18633e67-df47-41a4-8ac4-eab9f95820d2, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_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 17:11:25,146 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, 18633e67-df47-41a4-8ac4-eab9f95820d2, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:29,655 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:29,696 - INFO - root.760156  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1595704)
2025-12-05 17:11:29,697 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:29,698 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, b49b19ac-7429-4253-a0f2-30d492ee73f1, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:29,699 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, b49b19ac-7429-4253-a0f2-30d492ee73f1, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" 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_19_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 17:11:29,703 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, b49b19ac-7429-4253-a0f2-30d492ee73f1, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:30,242 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:30,258 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:30,259 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, de2ce02d-1c20-45ca-af0a-cee8b3015ec5, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:30,260 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, de2ce02d-1c20-45ca-af0a-cee8b3015ec5, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_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 17:11:30,278 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, de2ce02d-1c20-45ca-af0a-cee8b3015ec5, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:34,722 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:44,767 - DEBUG - ydb.query.pool.760156  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:44,768 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(ExecuteQuery, cad1e01b-eeb4-434f-b12b-b39b441bb992, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:44,769 - DEBUG - ydb.connection.760156  - _log_request: RpcState(ExecuteQuery, cad1e01b-eeb4-434f-b12b-b39b441bb992, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_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 17:11:44,770 - DEBUG - ydb.connection.760156  - _log_response: RpcState(ExecuteQuery, cad1e01b-eeb4-434f-b12b-b39b441bb992, ghrun-a66hhqwkt4.auto.internal:8294): response = { <_MultiThreadedRendezvous object> }
2025-12-05 17:11:49,899 - DEBUG - ydb.query.pool.760156  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==
2025-12-05 17:11:50,013 - INFO - root.760156  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1595704)
2025-12-05 17:11:50,014 - INFO - root.760156  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_19_compression` 8092160 / 1595704: 5.071216215538722
2025-12-05 17:11:50,015 - INFO - ya.test.760156  - pytest_runtest_teardown: Test teardown
2025-12-05 17:11:50,016 - DEBUG - ydb.connection.760156  - _prepare_call: RpcState(DeleteSession, 52958f00-01f9-4e04-b504-c455fb7f656e, ghrun-a66hhqwkt4.auto.internal:8294): creating call state
2025-12-05 17:11:50,017 - DEBUG - ydb.connection.760156  - _log_request: RpcState(DeleteSession, 52958f00-01f9-4e04-b504-c455fb7f656e, ghrun-a66hhqwkt4.auto.internal:8294): request = { session_id: "ydb://session/3?node_id=1&id=YjAwYmZkMC02NTNmNWY3Ny1mMmJlNDkxYS0yYzRkOTNlYw==" }
2025-12-05 17:11:50,031 - DEBUG - ydb.connection.760156  - _log_response: RpcState(DeleteSession, 52958f00-01f9-4e04-b504-c455fb7f656e, ghrun-a66hhqwkt4.auto.internal:8294): response = { status: SUCCESS }
2025-12-05 17:11:50,032 - DEBUG - ydb.query.pool.760156  - stop: All session were deleted.
2025-12-05 17:11:50,032 - DEBUG - ydb.topic.760156  - close: Close topic client
2025-12-05 17:11:50,033 - DEBUG - ydb.topic.760156  - close: Topic client was closed
2025-12-05 17:11:50,033 - DEBUG - ydb.connection.760156  - close: Closing channel for endpoint localhost:8294
2025-12-05 17:11:50,036 - DEBUG - ydb.connection.760156  - close: Closing channel for endpoint ghrun-a66hhqwkt4.auto.internal:8294
2025-12-05 17:11:50,039 - INFO - ydb.pool.Discovery.760156  - run: Successfully terminated discovery process
2025-12-05 17:12:07,054 - INFO - ydb.tests.library.harness.kikimr_runner.760156  - stop: Stopped node localhost:8294/1
2025-12-05 17:12:07,055 - DEBUG - library.python.filelock.760156  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1165
2025-12-05 17:12:07,056 - DEBUG - library.python.filelock.760156  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14315
2025-12-05 17:12:07,062 - DEBUG - library.python.filelock.760156  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13725
2025-12-05 17:12:07,062 - DEBUG - library.python.filelock.760156  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8294
2025-12-05 17:12:07,062 - DEBUG - library.python.filelock.760156  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32730
