2025-12-23 17:52:35,719 - INFO - ya.test.2335780  - pytest_runtest_setup: ####################################################################################################
2025-12-23 17:52:35,720 - INFO - ya.test.2335780  - pytest_runtest_setup: test_all_supported_compression[zstd_7_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 7]
2025-12-23 17:52:35,720 - INFO - ya.test.2335780  - pytest_runtest_setup: ####################################################################################################
2025-12-23 17:52:35,720 - INFO - ya.test.2335780  - pytest_runtest_setup: Test setup
2025-12-23 17:52:35,722 - INFO - ya.test.2335780  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_7_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 7])
2025-12-23 17:52:35,723 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:35,724 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 09226a76-ec66-42da-bc1c-ce15dddd430e, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:35,725 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 09226a76-ec66-42da-bc1c-ce15dddd430e, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_7_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 7)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:35,729 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 09226a76-ec66-42da-bc1c-ce15dddd430e, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:39,377 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:39,378 - INFO - __tests__.alter_compression.2335780  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_7_compression created
2025-12-23 17:52:39,379 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:39,379 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, d73844cc-0a35-4677-97c0-e9883d2adb47, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:39,380 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, d73844cc-0a35-4677-97c0-e9883d2adb47, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_7_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:39,383 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, d73844cc-0a35-4677-97c0-e9883d2adb47, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:40,413 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:40,414 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:40,415 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 22cbb5d7-deec-4998-80a2-daa7d11b7d3b, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:40,416 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 22cbb5d7-deec-4998-80a2-daa7d11b7d3b, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:40,429 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 22cbb5d7-deec-4998-80a2-daa7d11b7d3b, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:42,405 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:42,405 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:42,406 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 30a8d4dd-ee4c-4b71-8f4b-452c262d96da, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:42,407 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 30a8d4dd-ee4c-4b71-8f4b-452c262d96da, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:42,425 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 30a8d4dd-ee4c-4b71-8f4b-452c262d96da, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:43,477 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:43,477 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:43,478 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 8a2eeaf7-8266-40d1-9cfc-a617a02c9873, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:43,479 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 8a2eeaf7-8266-40d1-9cfc-a617a02c9873, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:43,481 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 8a2eeaf7-8266-40d1-9cfc-a617a02c9873, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:45,549 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:45,550 - INFO - __tests__.alter_compression.2335780  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-12-23 17:52:45,550 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:45,551 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 03f6bb39-273b-4283-85a9-4adf81e1598b, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:45,552 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 03f6bb39-273b-4283-85a9-4adf81e1598b, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:45,553 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 03f6bb39-273b-4283-85a9-4adf81e1598b, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:47,558 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:47,559 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:47,559 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 8ec89341-21d9-47a6-8f4e-6b6f15a42f9e, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:47,560 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 8ec89341-21d9-47a6-8f4e-6b6f15a42f9e, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:47,562 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 8ec89341-21d9-47a6-8f4e-6b6f15a42f9e, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:47,927 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:47,928 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:47,929 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 11379669-342c-4735-a01b-fef2176fcb42, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:47,930 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 11379669-342c-4735-a01b-fef2176fcb42, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:47,934 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 11379669-342c-4735-a01b-fef2176fcb42, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:48,490 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:48,581 - INFO - __tests__.alter_compression.2335780  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-12-23 17:52:48,581 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:48,582 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 3084129a-4d70-4a78-9299-76aa87cdcdf1, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:48,583 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 3084129a-4d70-4a78-9299-76aa87cdcdf1, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:48,585 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 3084129a-4d70-4a78-9299-76aa87cdcdf1, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:50,186 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:50,187 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:50,187 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, fc3262a7-40b9-4d72-b34b-4ac1327b1dab, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:50,188 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, fc3262a7-40b9-4d72-b34b-4ac1327b1dab, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:50,190 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, fc3262a7-40b9-4d72-b34b-4ac1327b1dab, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:50,538 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:50,539 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:50,540 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 8c21c20a-9508-43e2-9823-06821ed52efd, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:50,541 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 8c21c20a-9508-43e2-9823-06821ed52efd, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:50,542 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 8c21c20a-9508-43e2-9823-06821ed52efd, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:51,253 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:51,254 - INFO - __tests__.alter_compression.2335780  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956352, 'BlobCount': 384}}
2025-12-23 17:52:51,254 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:51,255 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 9ec4c096-5dcc-4222-9399-e5d44784c1d2, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:51,256 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 9ec4c096-5dcc-4222-9399-e5d44784c1d2, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:51,258 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 9ec4c096-5dcc-4222-9399-e5d44784c1d2, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:53,163 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:53,164 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:53,165 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 193c9891-10ee-4101-9a28-09dfa2f57030, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:53,166 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 193c9891-10ee-4101-9a28-09dfa2f57030, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:53,167 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 193c9891-10ee-4101-9a28-09dfa2f57030, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:53,474 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:53,475 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:53,475 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, ce8d6150-ddde-4691-927a-9aefa0c02c6d, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:53,476 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, ce8d6150-ddde-4691-927a-9aefa0c02c6d, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:53,478 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, ce8d6150-ddde-4691-927a-9aefa0c02c6d, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:54,107 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:54,108 - INFO - __tests__.alter_compression.2335780  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275712, 'BlobCount': 512}}
2025-12-23 17:52:54,109 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:54,109 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, dc0304d5-6cb1-499c-abee-d15427294805, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:54,110 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, dc0304d5-6cb1-499c-abee-d15427294805, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:54,116 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, dc0304d5-6cb1-499c-abee-d15427294805, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:56,161 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:56,161 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:56,162 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 0dccb7eb-9610-4dce-b6f0-f271816659a3, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:56,163 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 0dccb7eb-9610-4dce-b6f0-f271816659a3, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:56,165 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 0dccb7eb-9610-4dce-b6f0-f271816659a3, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:56,561 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:56,562 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:56,563 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, f3c7e415-e84d-4396-9262-4f4fe9361d9f, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:56,564 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, f3c7e415-e84d-4396-9262-4f4fe9361d9f, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:56,566 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, f3c7e415-e84d-4396-9262-4f4fe9361d9f, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:57,100 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:57,100 - INFO - __tests__.alter_compression.2335780  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594672, 'BlobCount': 640}}
2025-12-23 17:52:57,101 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:57,101 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 14fce093-1420-4cef-b4c2-d5ad62a7731e, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:57,102 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 14fce093-1420-4cef-b4c2-d5ad62a7731e, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:57,103 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 14fce093-1420-4cef-b4c2-d5ad62a7731e, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:59,074 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:59,075 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:59,075 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 384f1ce3-38d8-4b27-a4a4-d4785925d542, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:59,076 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 384f1ce3-38d8-4b27-a4a4-d4785925d542, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:59,078 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 384f1ce3-38d8-4b27-a4a4-d4785925d542, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:59,425 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:59,426 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:52:59,427 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 3ec78ef5-d469-4378-8a74-749b4004f926, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:52:59,427 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 3ec78ef5-d469-4378-8a74-749b4004f926, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:59,429 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 3ec78ef5-d469-4378-8a74-749b4004f926, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:00,032 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:00,033 - INFO - __tests__.alter_compression.2335780  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913856, 'BlobCount': 768}}
2025-12-23 17:53:00,033 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:00,034 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 888e0a84-b8c5-44d7-ad01-a6cae6f0f7af, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:00,035 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 888e0a84-b8c5-44d7-ad01-a6cae6f0f7af, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:00,041 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 888e0a84-b8c5-44d7-ad01-a6cae6f0f7af, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:01,665 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:01,665 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:01,666 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 97f702bf-9c79-4083-bdcb-d0a6720e878f, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:01,667 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 97f702bf-9c79-4083-bdcb-d0a6720e878f, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:01,668 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 97f702bf-9c79-4083-bdcb-d0a6720e878f, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:02,022 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:02,023 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:02,023 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, f4b9f999-8211-4ca7-b603-67cb02bc242b, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:02,024 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, f4b9f999-8211-4ca7-b603-67cb02bc242b, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:02,026 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, f4b9f999-8211-4ca7-b603-67cb02bc242b, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:03,331 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:03,332 - INFO - __tests__.alter_compression.2335780  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2232992, 'BlobCount': 896}}
2025-12-23 17:53:03,332 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:03,333 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 13872f6f-f0b1-4f38-b068-b2066d841329, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:03,334 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 13872f6f-f0b1-4f38-b068-b2066d841329, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:03,338 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 13872f6f-f0b1-4f38-b068-b2066d841329, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:05,325 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:05,326 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:05,327 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 272af59c-08e7-4c71-b1b3-9e8e49b9a17d, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:05,328 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 272af59c-08e7-4c71-b1b3-9e8e49b9a17d, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:05,330 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 272af59c-08e7-4c71-b1b3-9e8e49b9a17d, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:05,631 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:05,632 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:05,633 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, c5900eb4-fa5e-4e1d-8cab-e74c4f7beb57, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:05,634 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, c5900eb4-fa5e-4e1d-8cab-e74c4f7beb57, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:05,636 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, c5900eb4-fa5e-4e1d-8cab-e74c4f7beb57, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:06,391 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:06,392 - INFO - __tests__.alter_compression.2335780  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552480, 'BlobCount': 1024}}
2025-12-23 17:53:06,393 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:06,394 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, be458b07-61f4-47f9-9cd7-203d68761611, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:06,395 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, be458b07-61f4-47f9-9cd7-203d68761611, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:06,399 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, be458b07-61f4-47f9-9cd7-203d68761611, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:08,507 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:08,507 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:08,508 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, da45f5bb-5902-49c0-900a-abca06fa3ad8, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:08,509 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, da45f5bb-5902-49c0-900a-abca06fa3ad8, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:08,510 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, da45f5bb-5902-49c0-900a-abca06fa3ad8, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:09,069 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:09,070 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:09,070 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 736dca9d-6bc9-4edd-8a34-75fc9269386b, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:09,071 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 736dca9d-6bc9-4edd-8a34-75fc9269386b, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:09,077 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 736dca9d-6bc9-4edd-8a34-75fc9269386b, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:10,198 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:10,199 - INFO - __tests__.alter_compression.2335780  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871488, 'BlobCount': 1152}}
2025-12-23 17:53:10,199 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:10,200 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, b880a611-dcd5-4bc0-b750-55177aed27cb, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:10,201 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, b880a611-dcd5-4bc0-b750-55177aed27cb, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:10,203 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, b880a611-dcd5-4bc0-b750-55177aed27cb, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:11,889 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:11,890 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:11,891 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, ddc64c30-1f17-4ade-9c4e-a0385e282102, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:11,892 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, ddc64c30-1f17-4ade-9c4e-a0385e282102, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:11,899 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, ddc64c30-1f17-4ade-9c4e-a0385e282102, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:12,273 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:12,274 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:12,274 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 5e3fe6ac-2826-4240-961f-dfdd2a85bb02, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:12,275 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 5e3fe6ac-2826-4240-961f-dfdd2a85bb02, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:12,277 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 5e3fe6ac-2826-4240-961f-dfdd2a85bb02, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:13,757 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:13,758 - INFO - __tests__.alter_compression.2335780  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3190992, 'BlobCount': 1280}}
2025-12-23 17:53:13,758 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:13,759 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 66fdb1cb-96c6-4e63-bcb1-79fb442190d6, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:13,760 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 66fdb1cb-96c6-4e63-bcb1-79fb442190d6, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_7_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:13,761 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 66fdb1cb-96c6-4e63-bcb1-79fb442190d6, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:15,328 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:15,329 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:15,330 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 4087cec7-4e4d-41af-9dfc-9805d26aac2e, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:15,331 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 4087cec7-4e4d-41af-9dfc-9805d26aac2e, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:15,332 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 4087cec7-4e4d-41af-9dfc-9805d26aac2e, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:15,665 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:15,666 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:15,667 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, bdaa8ada-a8e1-4a2c-b006-5eeca6487e92, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:15,668 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, bdaa8ada-a8e1-4a2c-b006-5eeca6487e92, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:15,673 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, bdaa8ada-a8e1-4a2c-b006-5eeca6487e92, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:16,113 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:16,125 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:16,126 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 86ec5ed2-a02c-4b8b-ad61-5ca373232e1d, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:16,127 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 86ec5ed2-a02c-4b8b-ad61-5ca373232e1d, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:16,131 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 86ec5ed2-a02c-4b8b-ad61-5ca373232e1d, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:22,029 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:32,241 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:32,242 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 58970a9b-d0e2-4045-8bf8-d19bbb12e7a0, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:32,243 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 58970a9b-d0e2-4045-8bf8-d19bbb12e7a0, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:32,249 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 58970a9b-d0e2-4045-8bf8-d19bbb12e7a0, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:43,028 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:43,189 - INFO - root.2335780  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_7_compression`, volumes `value` (8000000, 1595496)
2025-12-23 17:53:43,190 - DEBUG - ydb.query.pool.2335780  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:43,191 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(ExecuteQuery, 04fd1fb6-e727-449d-931b-8fca1af00d76, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:43,192 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(ExecuteQuery, 04fd1fb6-e727-449d-931b-8fca1af00d76, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:53:43,193 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(ExecuteQuery, 04fd1fb6-e727-449d-931b-8fca1af00d76, ghrun-yh5r7pxf7y.auto.internal:63283): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:53:44,542 - DEBUG - ydb.query.pool.2335780  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=
2025-12-23 17:53:46,899 - ERROR - ya.test.2335780  - logreport: ydb/tests/olap/column_family/compression/alter_compression.py:194: in test_all_supported_compression
    assert table.get_portion_stat_by_tier()['__DEFAULT']['Rows'] == expected_raw // 8
E   assert 1015829 == (8000000 // 8)
2025-12-23 17:53:46,935 - INFO - ya.test.2335780  - pytest_runtest_teardown: Test teardown
2025-12-23 17:53:46,936 - DEBUG - ydb.connection.2335780  - _prepare_call: RpcState(DeleteSession, 73972b61-3ef5-4585-96af-d85d45cf6c8d, ghrun-yh5r7pxf7y.auto.internal:63283): creating call state
2025-12-23 17:53:46,937 - DEBUG - ydb.connection.2335780  - _log_request: RpcState(DeleteSession, 73972b61-3ef5-4585-96af-d85d45cf6c8d, ghrun-yh5r7pxf7y.auto.internal:63283): request = { session_id: "ydb://session/3?node_id=1&id=ZWYzNDc1ZDctMTdlN2MxZWQtN2Q3NDBiNmQtNjhlZTU2NTU=" }
2025-12-23 17:53:46,943 - DEBUG - ydb.connection.2335780  - _log_response: RpcState(DeleteSession, 73972b61-3ef5-4585-96af-d85d45cf6c8d, ghrun-yh5r7pxf7y.auto.internal:63283): response = { status: SUCCESS }
2025-12-23 17:53:46,945 - DEBUG - ydb.query.pool.2335780  - stop: All session were deleted.
2025-12-23 17:53:46,946 - DEBUG - ydb.topic.2335780  - close: Close topic client
2025-12-23 17:53:46,946 - DEBUG - ydb.topic.2335780  - close: Topic client was closed
2025-12-23 17:53:46,947 - DEBUG - ydb.connection.2335780  - close: Closing channel for endpoint localhost:63283
2025-12-23 17:53:46,952 - DEBUG - ydb.connection.2335780  - _rpc_error_handler: RpcState(AttachSession, 753c9eee-9079-4923-a777-0b8bc11a20af, ghrun-yh5r7pxf7y.auto.internal:63283): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-23 17:53:46,955 - DEBUG - ydb.connection.2335780  - close: Closing channel for endpoint ghrun-yh5r7pxf7y.auto.internal:63283
2025-12-23 17:53:46,963 - INFO - ydb.pool.Discovery.2335780  - run: Successfully terminated discovery process
2025-12-23 17:54:09,007 - INFO - ydb.tests.library.harness.kikimr_runner.2335780  - stop: Stopped node localhost:63283/1
2025-12-23 17:54:09,024 - DEBUG - library.python.filelock.2335780  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6650
2025-12-23 17:54:09,025 - DEBUG - library.python.filelock.2335780  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22520
2025-12-23 17:54:09,026 - DEBUG - library.python.filelock.2335780  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64405
2025-12-23 17:54:09,026 - DEBUG - library.python.filelock.2335780  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63283
2025-12-23 17:54:09,027 - DEBUG - library.python.filelock.2335780  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20628
