2025-12-05 16:21:26,986 - INFO - ya.test.22781  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:21:26,992 - INFO - ya.test.22781  - pytest_runtest_setup: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16]
2025-12-05 16:21:27,001 - INFO - ya.test.22781  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:21:27,016 - INFO - ya.test.22781  - pytest_runtest_setup: Test setup
2025-12-05 16:21:27,027 - INFO - ya.test.22781  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16])
2025-12-05 16:21:27,030 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:27,040 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, ab4c7621-b6b1-4d80-b2d3-edaede30d286, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:27,049 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, ab4c7621-b6b1-4d80-b2d3-edaede30d286, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_16_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 16)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:27,061 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, ab4c7621-b6b1-4d80-b2d3-edaede30d286, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:29,253 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:29,254 - INFO - __tests__.alter_compression.22781  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_16_compression created
2025-12-05 16:21:29,254 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:29,255 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, ad3084d3-5d77-4fa9-8f82-8cc902bed370, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:29,255 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, ad3084d3-5d77-4fa9-8f82-8cc902bed370, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:29,256 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, ad3084d3-5d77-4fa9-8f82-8cc902bed370, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:29,975 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:29,984 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:29,985 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, df2554f2-c0f9-4a2b-85e9-75b01efafe8d, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:29,986 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, df2554f2-c0f9-4a2b-85e9-75b01efafe8d, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:29,987 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, df2554f2-c0f9-4a2b-85e9-75b01efafe8d, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:33,186 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:33,187 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:33,187 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 4421207c-9d5e-44e2-a89f-c2cd40f75e4f, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:33,188 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 4421207c-9d5e-44e2-a89f-c2cd40f75e4f, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:33,189 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 4421207c-9d5e-44e2-a89f-c2cd40f75e4f, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:33,997 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:34,019 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:34,032 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 269cb124-08ff-4b4d-a39d-13d1da5b3ff1, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:34,033 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 269cb124-08ff-4b4d-a39d-13d1da5b3ff1, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:34,041 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 269cb124-08ff-4b4d-a39d-13d1da5b3ff1, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:35,276 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:35,278 - INFO - __tests__.alter_compression.22781  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-12-05 16:21:35,287 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:35,287 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 9c7ab6aa-e8c1-4c35-b0a3-2676fe26f89d, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:35,296 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 9c7ab6aa-e8c1-4c35-b0a3-2676fe26f89d, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:35,297 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 9c7ab6aa-e8c1-4c35-b0a3-2676fe26f89d, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:38,172 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:38,173 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:38,173 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 4cdc9823-4e2a-4821-9077-79a961d734f8, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:38,174 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 4cdc9823-4e2a-4821-9077-79a961d734f8, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:38,175 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 4cdc9823-4e2a-4821-9077-79a961d734f8, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:38,504 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:38,505 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:38,506 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 196cd6d4-b003-4382-b103-288a70c8d0a3, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:38,507 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 196cd6d4-b003-4382-b103-288a70c8d0a3, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:38,508 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 196cd6d4-b003-4382-b103-288a70c8d0a3, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:38,790 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:38,791 - INFO - __tests__.alter_compression.22781  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-12-05 16:21:38,791 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:38,792 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 8e339388-844a-4b61-97f9-fd0e2b93c176, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:38,793 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 8e339388-844a-4b61-97f9-fd0e2b93c176, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:38,794 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 8e339388-844a-4b61-97f9-fd0e2b93c176, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:42,358 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:42,368 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:42,369 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 2d854ef4-592b-4337-89c0-5a0ecfe28acc, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:42,370 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 2d854ef4-592b-4337-89c0-5a0ecfe28acc, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:42,371 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 2d854ef4-592b-4337-89c0-5a0ecfe28acc, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:42,625 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:42,626 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:42,626 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 11b3b78c-65c4-4c16-a9d2-80540d0a106c, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:42,654 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 11b3b78c-65c4-4c16-a9d2-80540d0a106c, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:42,656 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 11b3b78c-65c4-4c16-a9d2-80540d0a106c, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:43,115 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:43,147 - INFO - __tests__.alter_compression.22781  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-12-05 16:21:43,147 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:43,148 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, e719bbbd-44a9-403b-a0d8-c2ed298c0449, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:43,149 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, e719bbbd-44a9-403b-a0d8-c2ed298c0449, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:43,151 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, e719bbbd-44a9-403b-a0d8-c2ed298c0449, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:46,866 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:46,867 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:46,868 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 4958c0d6-fc1a-4f43-a5d1-bdcd218dfef2, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:46,868 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 4958c0d6-fc1a-4f43-a5d1-bdcd218dfef2, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:46,870 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 4958c0d6-fc1a-4f43-a5d1-bdcd218dfef2, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:47,194 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:47,195 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:47,196 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 7cff6a30-65c3-4649-bea9-08cd76192ba9, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:47,197 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 7cff6a30-65c3-4649-bea9-08cd76192ba9, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:47,198 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 7cff6a30-65c3-4649-bea9-08cd76192ba9, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:47,630 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:47,657 - INFO - __tests__.alter_compression.22781  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-12-05 16:21:47,658 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:47,659 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, e9f0d327-8fa7-452f-842c-ad304f72f096, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:47,660 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, e9f0d327-8fa7-452f-842c-ad304f72f096, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:47,661 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, e9f0d327-8fa7-452f-842c-ad304f72f096, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:51,545 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:51,546 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:51,546 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 454f3b4e-8567-432c-8e7c-c6f10738609b, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:51,547 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 454f3b4e-8567-432c-8e7c-c6f10738609b, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:51,551 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 454f3b4e-8567-432c-8e7c-c6f10738609b, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:51,862 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:51,863 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:51,864 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 4a3289db-a7e4-4c85-846d-a05a8bbb7482, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:51,865 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 4a3289db-a7e4-4c85-846d-a05a8bbb7482, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:51,866 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 4a3289db-a7e4-4c85-846d-a05a8bbb7482, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:52,332 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:52,332 - INFO - __tests__.alter_compression.22781  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-12-05 16:21:52,333 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:52,334 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 53ee7fe4-c0be-4dcc-a04b-a8c2085aa404, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:52,334 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 53ee7fe4-c0be-4dcc-a04b-a8c2085aa404, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:52,336 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 53ee7fe4-c0be-4dcc-a04b-a8c2085aa404, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:56,662 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:56,663 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:56,664 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 1b571b21-23f2-49cd-95ff-b8aa09b8a1fa, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:56,665 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 1b571b21-23f2-49cd-95ff-b8aa09b8a1fa, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:56,666 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 1b571b21-23f2-49cd-95ff-b8aa09b8a1fa, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:56,970 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:56,970 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:56,971 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, c70cbd8c-da57-4017-b505-98c421b32a5b, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:56,972 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, c70cbd8c-da57-4017-b505-98c421b32a5b, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:56,973 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, c70cbd8c-da57-4017-b505-98c421b32a5b, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:57,601 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:57,620 - INFO - __tests__.alter_compression.22781  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-12-05 16:21:57,621 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:21:57,622 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, c5828fff-bfde-4018-a16d-d5ba37057c18, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:21:57,623 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, c5828fff-bfde-4018-a16d-d5ba37057c18, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:57,624 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, c5828fff-bfde-4018-a16d-d5ba37057c18, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:02,309 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:02,310 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:02,311 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 2fda3eac-53d6-4c5d-9972-b11cd80da488, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:02,312 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 2fda3eac-53d6-4c5d-9972-b11cd80da488, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:02,314 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 2fda3eac-53d6-4c5d-9972-b11cd80da488, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:02,605 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:02,606 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:02,607 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 71055fd1-9b28-4b89-ae0c-a881c40ae64b, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:02,608 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 71055fd1-9b28-4b89-ae0c-a881c40ae64b, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:02,610 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 71055fd1-9b28-4b89-ae0c-a881c40ae64b, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:03,142 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:03,143 - INFO - __tests__.alter_compression.22781  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-12-05 16:22:03,143 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:03,144 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 0e45425a-d7db-438c-ae1a-bef76245553e, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:03,145 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 0e45425a-d7db-438c-ae1a-bef76245553e, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:03,147 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 0e45425a-d7db-438c-ae1a-bef76245553e, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:06,625 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:06,628 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:06,628 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, a95c1b27-a703-43d7-a870-f4de45bca973, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:06,635 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, a95c1b27-a703-43d7-a870-f4de45bca973, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:06,636 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, a95c1b27-a703-43d7-a870-f4de45bca973, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:06,969 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:06,970 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:06,971 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 46149d03-1289-4ff2-afa5-03945624b635, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:06,981 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 46149d03-1289-4ff2-afa5-03945624b635, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:06,998 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 46149d03-1289-4ff2-afa5-03945624b635, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:07,577 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:07,590 - INFO - __tests__.alter_compression.22781  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-12-05 16:22:07,591 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:07,591 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 36dd28cc-4867-4c52-b5c6-fb7f1aff8240, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:07,593 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 36dd28cc-4867-4c52-b5c6-fb7f1aff8240, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:07,594 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 36dd28cc-4867-4c52-b5c6-fb7f1aff8240, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:10,918 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:10,920 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:10,920 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, d21ab943-d8f3-4b58-8fe2-22c0bc3b085e, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:10,921 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, d21ab943-d8f3-4b58-8fe2-22c0bc3b085e, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:10,923 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, d21ab943-d8f3-4b58-8fe2-22c0bc3b085e, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:11,219 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:11,220 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:11,221 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 106a721f-0d33-4f46-8de3-5a31d0dd19ee, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:11,222 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 106a721f-0d33-4f46-8de3-5a31d0dd19ee, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:11,223 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 106a721f-0d33-4f46-8de3-5a31d0dd19ee, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:11,787 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:11,787 - INFO - __tests__.alter_compression.22781  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-12-05 16:22:11,788 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:11,788 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 88c9117c-f015-4f37-accb-a2576eb3c47c, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:11,789 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 88c9117c-f015-4f37-accb-a2576eb3c47c, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:11,791 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 88c9117c-f015-4f37-accb-a2576eb3c47c, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:14,790 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:14,796 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:14,797 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 07bc43cd-3b4c-4d23-9f70-42b7c2ed0d7d, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:14,806 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 07bc43cd-3b4c-4d23-9f70-42b7c2ed0d7d, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:14,807 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 07bc43cd-3b4c-4d23-9f70-42b7c2ed0d7d, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:15,037 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:15,038 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:15,038 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, e2c34ab3-5d5d-433e-8339-6bbe9e6eca61, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:15,039 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, e2c34ab3-5d5d-433e-8339-6bbe9e6eca61, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:15,040 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, e2c34ab3-5d5d-433e-8339-6bbe9e6eca61, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:15,614 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:15,615 - INFO - __tests__.alter_compression.22781  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-12-05 16:22:15,615 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:15,616 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 88115c86-2100-4446-8f4b-468ff525ee10, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:15,616 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 88115c86-2100-4446-8f4b-468ff525ee10, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:15,617 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 88115c86-2100-4446-8f4b-468ff525ee10, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:16,419 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:16,419 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:16,420 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 70655af4-401a-47a2-a89f-bdbcdf5fd67e, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:16,420 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 70655af4-401a-47a2-a89f-bdbcdf5fd67e, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:16,421 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 70655af4-401a-47a2-a89f-bdbcdf5fd67e, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:16,592 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:16,593 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:16,594 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, cd62375d-fb31-4859-b074-ab0c5833fa29, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:16,595 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, cd62375d-fb31-4859-b074-ab0c5833fa29, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:16,597 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, cd62375d-fb31-4859-b074-ab0c5833fa29, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:16,784 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:16,785 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:16,785 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 8d79e896-08a2-4396-a0c7-d5bc9bfce11c, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:16,786 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 8d79e896-08a2-4396-a0c7-d5bc9bfce11c, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:16,788 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 8d79e896-08a2-4396-a0c7-d5bc9bfce11c, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:19,571 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:29,608 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:29,609 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, c04fc65c-796a-4e56-972a-a67be7c5b6c5, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:29,610 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, c04fc65c-796a-4e56-972a-a67be7c5b6c5, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:29,611 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, c04fc65c-796a-4e56-972a-a67be7c5b6c5, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:30,353 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22781  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:22:30,353 - DEBUG - ydb.connection.22781  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 16:22:30,361 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22781  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:22:30,361 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ListEndpoints, 6ed98af6-7a36-4a99-bf38-7886c67e2bdb, localhost:11351): creating call state
2025-12-05 16:22:30,362 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ListEndpoints, 6ed98af6-7a36-4a99-bf38-7886c67e2bdb, localhost:11351): request = { database: "/Root" }
2025-12-05 16:22:30,578 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ListEndpoints, 6ed98af6-7a36-4a99-bf38-7886c67e2bdb, localhost:11351): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 11351 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-05 16:22:30,581 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22781  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:11351, location 1, ssl: False>]>
2025-12-05 16:22:30,581 - DEBUG - ydb.connection.22781  - close: Closing channel for endpoint localhost:11351
2025-12-05 16:22:32,150 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:32,174 - INFO - root.22781  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1588112)
2025-12-05 16:22:32,175 - DEBUG - ydb.query.pool.22781  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:32,175 - DEBUG - ydb.connection.22781  - _prepare_call: RpcState(ExecuteQuery, 3fc0918e-b5de-42e2-a17a-c8d7073252b1, ghrun-a66hhqwkt4.auto.internal:11351): creating call state
2025-12-05 16:22:32,176 - DEBUG - ydb.connection.22781  - _log_request: RpcState(ExecuteQuery, 3fc0918e-b5de-42e2-a17a-c8d7073252b1, ghrun-a66hhqwkt4.auto.internal:11351): request = { session_id: "ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:22:32,177 - DEBUG - ydb.connection.22781  - _log_response: RpcState(ExecuteQuery, 3fc0918e-b5de-42e2-a17a-c8d7073252b1, ghrun-a66hhqwkt4.auto.internal:11351): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:22:32,475 - DEBUG - ydb.query.pool.22781  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzZmODI5MjYtNGRjOGM5MGEtNTJjOTYwZWQtZTlhNTgwZg==
2025-12-05 16:22:33,104 - ERROR - ya.test.22781  - 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 1015673 == (8000000 // 8)
2025-12-05 16:22:33,106 - INFO - ya.test.22781  - pytest_runtest_teardown: Test teardown
