2025-12-04 21:07:22,883 - INFO - ya.test.704012  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:07:22,883 - INFO - ya.test.704012  - pytest_runtest_setup: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14]
2025-12-04 21:07:22,883 - INFO - ya.test.704012  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:07:22,883 - INFO - ya.test.704012  - pytest_runtest_setup: Test setup
2025-12-04 21:07:22,885 - INFO - ya.test.704012  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14])
2025-12-04 21:07:22,885 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:22,886 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 61453cac-16db-40c8-80a9-604422782834, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:22,886 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 61453cac-16db-40c8-80a9-604422782834, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_14_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 14)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:22,887 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 61453cac-16db-40c8-80a9-604422782834, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:24,449 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:24,449 - INFO - __tests__.alter_compression.704012  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_14_compression created
2025-12-04 21:07:24,449 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:24,450 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, f4e3f118-4775-4719-8b06-5dbc83baa751, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:24,450 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, f4e3f118-4775-4719-8b06-5dbc83baa751, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:24,451 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, f4e3f118-4775-4719-8b06-5dbc83baa751, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:24,847 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:24,848 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:24,848 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, a27b8927-1de9-439a-ad46-cda203f39ebe, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:24,848 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, a27b8927-1de9-439a-ad46-cda203f39ebe, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:24,849 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, a27b8927-1de9-439a-ad46-cda203f39ebe, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:26,483 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:26,483 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:26,484 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, b0a5aa6c-0f2c-4896-a7af-cd1d81df9120, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:26,484 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, b0a5aa6c-0f2c-4896-a7af-cd1d81df9120, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:26,485 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, b0a5aa6c-0f2c-4896-a7af-cd1d81df9120, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:27,138 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:27,139 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:27,140 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, db1fe0cd-fa74-4353-8fed-bed861594d72, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:27,140 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, db1fe0cd-fa74-4353-8fed-bed861594d72, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:27,141 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, db1fe0cd-fa74-4353-8fed-bed861594d72, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:28,312 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:28,312 - INFO - __tests__.alter_compression.704012  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-12-04 21:07:28,312 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:28,313 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 7d3af612-014f-4417-8704-c19a9f93a5e5, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:28,313 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 7d3af612-014f-4417-8704-c19a9f93a5e5, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:28,314 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 7d3af612-014f-4417-8704-c19a9f93a5e5, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:30,005 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:30,005 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:30,006 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, f7630a9b-3ded-4c52-a91c-5266ac5c8b6a, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:30,007 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, f7630a9b-3ded-4c52-a91c-5266ac5c8b6a, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:30,008 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, f7630a9b-3ded-4c52-a91c-5266ac5c8b6a, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:30,247 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:30,248 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:30,249 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, e3088aae-b148-49e9-81d8-49e03c99c5dd, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:30,250 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, e3088aae-b148-49e9-81d8-49e03c99c5dd, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:30,251 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, e3088aae-b148-49e9-81d8-49e03c99c5dd, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:30,538 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:30,539 - INFO - __tests__.alter_compression.704012  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-12-04 21:07:30,539 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:30,540 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 0ed1152e-e9bf-4fcc-ba4c-a86a8eadd085, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:30,541 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 0ed1152e-e9bf-4fcc-ba4c-a86a8eadd085, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:30,542 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 0ed1152e-e9bf-4fcc-ba4c-a86a8eadd085, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:32,238 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:32,239 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:32,240 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, a10a2334-9432-460c-9264-f859d6a5bcff, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:32,240 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, a10a2334-9432-460c-9264-f859d6a5bcff, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:32,241 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, a10a2334-9432-460c-9264-f859d6a5bcff, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:32,416 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:32,417 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:32,417 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 51d42ff0-2cd0-4764-bd4b-80d2630203b3, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:32,418 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 51d42ff0-2cd0-4764-bd4b-80d2630203b3, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:32,419 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 51d42ff0-2cd0-4764-bd4b-80d2630203b3, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:32,730 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:32,731 - INFO - __tests__.alter_compression.704012  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-12-04 21:07:32,731 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:32,732 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, d963ed88-0b26-4238-b958-23de8a8295e8, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:32,733 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, d963ed88-0b26-4238-b958-23de8a8295e8, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:32,734 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, d963ed88-0b26-4238-b958-23de8a8295e8, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:34,777 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:34,778 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:34,779 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 85ab7c70-bce6-47ed-afbd-c417afa91777, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:34,779 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 85ab7c70-bce6-47ed-afbd-c417afa91777, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:34,781 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 85ab7c70-bce6-47ed-afbd-c417afa91777, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:34,996 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:35,004 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:35,008 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 6028567a-dd4a-40e2-8b21-42013c3b929e, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:35,011 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 6028567a-dd4a-40e2-8b21-42013c3b929e, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:35,014 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 6028567a-dd4a-40e2-8b21-42013c3b929e, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:35,452 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:35,453 - INFO - __tests__.alter_compression.704012  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-12-04 21:07:35,453 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:35,454 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 4004f9b3-2d23-4bf1-b89c-5fe2316f56b6, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:35,455 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 4004f9b3-2d23-4bf1-b89c-5fe2316f56b6, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:35,458 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 4004f9b3-2d23-4bf1-b89c-5fe2316f56b6, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:35,464 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.704012  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:07:35,464 - DEBUG - ydb.connection.704012  - 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-04 21:07:35,474 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.704012  - context_resolve: Resolving endpoints for database /Root
2025-12-04 21:07:35,474 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ListEndpoints, 259a312e-66f6-46a3-88fe-d667fb8aad4a, localhost:20061): creating call state
2025-12-04 21:07:35,475 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ListEndpoints, 259a312e-66f6-46a3-88fe-d667fb8aad4a, localhost:20061): request = { database: "/Root" }
2025-12-04 21:07:35,479 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ListEndpoints, 259a312e-66f6-46a3-88fe-d667fb8aad4a, localhost:20061): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-fryxcz52gi.auto.internal" port: 20061 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-04 21:07:35,480 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.704012  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-fryxcz52gi.auto.internal:20061, location 1, ssl: False>]>
2025-12-04 21:07:35,484 - DEBUG - ydb.connection.704012  - close: Closing channel for endpoint localhost:20061
2025-12-04 21:07:37,386 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:37,386 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:37,387 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 38ed1cfa-72e0-483d-aad3-331f802b7360, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:37,388 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 38ed1cfa-72e0-483d-aad3-331f802b7360, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:37,390 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 38ed1cfa-72e0-483d-aad3-331f802b7360, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:37,560 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:37,560 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:37,561 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 54e23d49-e7a7-4439-9075-c5b8dc5b4acc, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:37,561 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 54e23d49-e7a7-4439-9075-c5b8dc5b4acc, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:37,562 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 54e23d49-e7a7-4439-9075-c5b8dc5b4acc, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:37,867 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:37,867 - INFO - __tests__.alter_compression.704012  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-12-04 21:07:37,868 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:37,868 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 19d866c4-0d99-4fa2-8f1e-a718f98c4c65, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:37,869 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 19d866c4-0d99-4fa2-8f1e-a718f98c4c65, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:37,870 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 19d866c4-0d99-4fa2-8f1e-a718f98c4c65, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:39,459 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:39,460 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:39,460 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 006314dc-87a8-4419-a280-c2fe86e70290, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:39,461 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 006314dc-87a8-4419-a280-c2fe86e70290, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:39,463 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 006314dc-87a8-4419-a280-c2fe86e70290, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:39,634 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:39,635 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:39,636 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, dc94a0e9-177d-4510-93ad-f09b62e5dd98, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:39,636 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, dc94a0e9-177d-4510-93ad-f09b62e5dd98, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:39,638 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, dc94a0e9-177d-4510-93ad-f09b62e5dd98, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:39,984 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:39,985 - INFO - __tests__.alter_compression.704012  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-12-04 21:07:39,985 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:39,986 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, ce1e4ae6-4429-4b5c-adca-1161794aa0d4, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:39,986 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, ce1e4ae6-4429-4b5c-adca-1161794aa0d4, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:39,987 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, ce1e4ae6-4429-4b5c-adca-1161794aa0d4, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:41,426 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:41,427 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:41,428 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 7f38006b-8b3e-414d-8785-e60b54ac7dda, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:41,429 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 7f38006b-8b3e-414d-8785-e60b54ac7dda, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:41,430 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 7f38006b-8b3e-414d-8785-e60b54ac7dda, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:41,605 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:41,606 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:41,607 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 5bf3e7db-5d22-476c-88cf-d13699936996, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:41,608 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 5bf3e7db-5d22-476c-88cf-d13699936996, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:41,610 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 5bf3e7db-5d22-476c-88cf-d13699936996, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:42,041 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:42,042 - INFO - __tests__.alter_compression.704012  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-12-04 21:07:42,042 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:42,043 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 8e92ec2a-e967-4352-80ef-bf2ef3d6e9f1, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:42,044 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 8e92ec2a-e967-4352-80ef-bf2ef3d6e9f1, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:42,045 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 8e92ec2a-e967-4352-80ef-bf2ef3d6e9f1, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:43,737 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:43,738 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:43,739 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 3816436d-2022-448a-a50f-3a4ea03a8a63, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:43,740 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 3816436d-2022-448a-a50f-3a4ea03a8a63, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:43,741 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 3816436d-2022-448a-a50f-3a4ea03a8a63, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:43,972 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:43,973 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:43,974 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, a4b43fc2-337a-43b0-a11b-38081abdae92, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:43,975 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, a4b43fc2-337a-43b0-a11b-38081abdae92, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:43,976 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, a4b43fc2-337a-43b0-a11b-38081abdae92, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:44,493 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:44,494 - INFO - __tests__.alter_compression.704012  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-12-04 21:07:44,494 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:44,495 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, fd07e28d-0190-4e9c-80e1-b80e78298549, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:44,496 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, fd07e28d-0190-4e9c-80e1-b80e78298549, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:44,497 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, fd07e28d-0190-4e9c-80e1-b80e78298549, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:46,504 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:46,504 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:46,505 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, b0eb7e4d-7b4d-43ae-b63d-78d7e6c65cef, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:46,506 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, b0eb7e4d-7b4d-43ae-b63d-78d7e6c65cef, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:46,508 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, b0eb7e4d-7b4d-43ae-b63d-78d7e6c65cef, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:46,732 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:46,732 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:46,733 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, f62e2bf3-0d85-49a6-ac26-47a3b7f6c40b, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:46,734 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, f62e2bf3-0d85-49a6-ac26-47a3b7f6c40b, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:46,735 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, f62e2bf3-0d85-49a6-ac26-47a3b7f6c40b, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:47,275 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:47,276 - INFO - __tests__.alter_compression.704012  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-12-04 21:07:47,276 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:47,277 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 83b03f13-c4ca-40a3-91ba-0aec3a40e88a, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:47,278 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 83b03f13-c4ca-40a3-91ba-0aec3a40e88a, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:47,279 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 83b03f13-c4ca-40a3-91ba-0aec3a40e88a, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:48,962 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:48,963 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:48,964 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, a134f522-0e01-451a-9a72-2a92dbcae930, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:48,964 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, a134f522-0e01-451a-9a72-2a92dbcae930, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:48,966 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, a134f522-0e01-451a-9a72-2a92dbcae930, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:49,129 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:49,129 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:49,130 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, e753dc41-2d1b-42a4-a289-b5bdfd3fc7af, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:49,130 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, e753dc41-2d1b-42a4-a289-b5bdfd3fc7af, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:49,131 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, e753dc41-2d1b-42a4-a289-b5bdfd3fc7af, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:49,565 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:49,565 - INFO - __tests__.alter_compression.704012  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-12-04 21:07:49,565 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:49,566 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 17b6cce3-9a51-4ad1-872f-c85336f5c7df, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:49,566 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 17b6cce3-9a51-4ad1-872f-c85336f5c7df, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:49,567 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 17b6cce3-9a51-4ad1-872f-c85336f5c7df, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:50,240 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:50,240 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:50,241 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, e8be270d-833b-4995-bfbb-c6b3c92565d9, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:50,241 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, e8be270d-833b-4995-bfbb-c6b3c92565d9, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:50,243 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, e8be270d-833b-4995-bfbb-c6b3c92565d9, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:50,408 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:50,409 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:50,410 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 5e68a307-5cf8-4006-b49f-baa0a59b6ccd, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:50,411 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 5e68a307-5cf8-4006-b49f-baa0a59b6ccd, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:50,412 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 5e68a307-5cf8-4006-b49f-baa0a59b6ccd, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:50,603 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:50,604 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:07:50,604 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, ce3fd0da-9159-4a4e-8511-129222d73481, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:07:50,608 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, ce3fd0da-9159-4a4e-8511-129222d73481, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:50,613 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, ce3fd0da-9159-4a4e-8511-129222d73481, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:52,951 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:08:02,985 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:08:02,985 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, ddd86ad5-74f2-47ec-8e04-05a999adb4f0, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:08:02,986 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, ddd86ad5-74f2-47ec-8e04-05a999adb4f0, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:08:02,987 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, ddd86ad5-74f2-47ec-8e04-05a999adb4f0, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:08:05,054 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:08:05,090 - INFO - root.704012  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1596216)
2025-12-04 21:08:05,091 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:08:05,091 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, afcc6678-1cb8-4ff7-93af-bbd9fe117ac0, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:08:05,092 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, afcc6678-1cb8-4ff7-93af-bbd9fe117ac0, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:08:05,094 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, afcc6678-1cb8-4ff7-93af-bbd9fe117ac0, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:08:05,263 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:08:05,264 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:08:05,265 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 115febb3-1a4b-4828-89dc-dd8afde79b27, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:08:05,265 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 115febb3-1a4b-4828-89dc-dd8afde79b27, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:08:05,266 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 115febb3-1a4b-4828-89dc-dd8afde79b27, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:08:07,256 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:08:17,309 - DEBUG - ydb.query.pool.704012  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:08:17,310 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(ExecuteQuery, 5225abaa-505c-4ba8-b6db-f8e7f5552513, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:08:17,310 - DEBUG - ydb.connection.704012  - _log_request: RpcState(ExecuteQuery, 5225abaa-505c-4ba8-b6db-f8e7f5552513, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:08:17,311 - DEBUG - ydb.connection.704012  - _log_response: RpcState(ExecuteQuery, 5225abaa-505c-4ba8-b6db-f8e7f5552513, ghrun-fryxcz52gi.auto.internal:20061): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:08:19,258 - DEBUG - ydb.query.pool.704012  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=
2025-12-04 21:08:19,287 - INFO - root.704012  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1596216)
2025-12-04 21:08:19,287 - INFO - root.704012  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_14_compression` 8092160 / 1596216: 5.069589579355175
2025-12-04 21:08:19,289 - INFO - ya.test.704012  - pytest_runtest_teardown: Test teardown
2025-12-04 21:08:19,289 - DEBUG - ydb.connection.704012  - _prepare_call: RpcState(DeleteSession, 9244b605-b84f-4bc1-9055-2aa09d041f90, ghrun-fryxcz52gi.auto.internal:20061): creating call state
2025-12-04 21:08:19,290 - DEBUG - ydb.connection.704012  - _log_request: RpcState(DeleteSession, 9244b605-b84f-4bc1-9055-2aa09d041f90, ghrun-fryxcz52gi.auto.internal:20061): request = { session_id: "ydb://session/3?node_id=1&id=ZTA4MWVmZjctOGRmMTNhYTYtZTFjNTEyZTMtZDBlYWQzYWQ=" }
2025-12-04 21:08:19,293 - DEBUG - ydb.connection.704012  - _log_response: RpcState(DeleteSession, 9244b605-b84f-4bc1-9055-2aa09d041f90, ghrun-fryxcz52gi.auto.internal:20061): response = { status: SUCCESS }
2025-12-04 21:08:19,294 - DEBUG - ydb.query.pool.704012  - stop: All session were deleted.
2025-12-04 21:08:19,295 - DEBUG - ydb.topic.704012  - close: Close topic client
2025-12-04 21:08:19,295 - DEBUG - ydb.connection.704012  - _rpc_error_handler: RpcState(AttachSession, 05050a3e-e675-4895-a690-6f49f3e5775d, ghrun-fryxcz52gi.auto.internal:20061): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-04 21:08:19,296 - DEBUG - ydb.topic.704012  - close: Topic client was closed
2025-12-04 21:08:19,299 - DEBUG - ydb.connection.704012  - close: Closing channel for endpoint localhost:20061
2025-12-04 21:08:19,300 - DEBUG - ydb.connection.704012  - close: Closing channel for endpoint ghrun-fryxcz52gi.auto.internal:20061
2025-12-04 21:08:19,303 - INFO - ydb.pool.Discovery.704012  - run: Successfully terminated discovery process
2025-12-04 21:08:31,306 - INFO - ydb.tests.library.harness.kikimr_runner.704012  - stop: Stopped node localhost:20061/1
2025-12-04 21:08:31,307 - DEBUG - library.python.filelock.704012  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1234
2025-12-04 21:08:31,308 - DEBUG - library.python.filelock.704012  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27727
2025-12-04 21:08:31,308 - DEBUG - library.python.filelock.704012  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8626
2025-12-04 21:08:31,309 - DEBUG - library.python.filelock.704012  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20061
2025-12-04 21:08:31,310 - DEBUG - library.python.filelock.704012  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23228
