2025-12-23 14:54:56,245 - INFO - ya.test.50224  - pytest_runtest_setup: ####################################################################################################
2025-12-23 14:54:56,245 - INFO - ya.test.50224  - pytest_runtest_setup: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11]
2025-12-23 14:54:56,246 - INFO - ya.test.50224  - pytest_runtest_setup: ####################################################################################################
2025-12-23 14:54:56,246 - INFO - ya.test.50224  - pytest_runtest_setup: Test setup
2025-12-23 14:54:56,248 - INFO - ya.test.50224  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_11_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 11])
2025-12-23 14:54:56,249 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:54:56,249 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 3aaafead-4190-4741-a859-3ba51c3a6406, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:54:56,250 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 3aaafead-4190-4741-a859-3ba51c3a6406, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_11_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 11)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:54:56,251 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 3aaafead-4190-4741-a859-3ba51c3a6406, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:54:59,216 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:54:59,216 - INFO - __tests__.alter_compression.50224  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_11_compression created
2025-12-23 14:54:59,217 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:54:59,217 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, ff18eae4-46e4-4ebc-9d4f-09dbb1b6e3e4, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:54:59,218 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, ff18eae4-46e4-4ebc-9d4f-09dbb1b6e3e4, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_11_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:54:59,220 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, ff18eae4-46e4-4ebc-9d4f-09dbb1b6e3e4, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:00,263 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:00,264 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:00,265 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, f60ce6be-9e1d-465c-bc27-d7f17858a98a, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:00,266 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, f60ce6be-9e1d-465c-bc27-d7f17858a98a, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:00,272 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, f60ce6be-9e1d-465c-bc27-d7f17858a98a, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:02,629 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:02,630 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:02,631 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 97c96e78-6092-4877-b236-57f218514e7d, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:02,632 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 97c96e78-6092-4877-b236-57f218514e7d, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:02,633 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 97c96e78-6092-4877-b236-57f218514e7d, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:03,863 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:03,863 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:03,864 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 73d8f2c8-adb8-49e1-a5a0-199629179657, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:03,865 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 73d8f2c8-adb8-49e1-a5a0-199629179657, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:03,866 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 73d8f2c8-adb8-49e1-a5a0-199629179657, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:05,866 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:05,867 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 375776, 'BlobCount': 128}}
2025-12-23 14:55:05,867 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:05,868 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 21a05b21-1a95-40bb-9fda-0347f0c5aabe, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:05,869 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 21a05b21-1a95-40bb-9fda-0347f0c5aabe, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:05,870 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 21a05b21-1a95-40bb-9fda-0347f0c5aabe, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:08,547 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:08,548 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:08,549 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 285a3b04-0fba-48b9-96bd-895f70861e54, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:08,550 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 285a3b04-0fba-48b9-96bd-895f70861e54, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:08,552 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 285a3b04-0fba-48b9-96bd-895f70861e54, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:09,012 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:09,013 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:09,014 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, d1ec2f5c-66c5-463c-b778-712526f0b7d1, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:09,015 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, d1ec2f5c-66c5-463c-b778-712526f0b7d1, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:09,016 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, d1ec2f5c-66c5-463c-b778-712526f0b7d1, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:09,466 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:09,466 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 753008, 'BlobCount': 256}}
2025-12-23 14:55:09,467 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:09,468 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 51b7d394-641f-41cc-b73a-d4929c89e575, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:09,468 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 51b7d394-641f-41cc-b73a-d4929c89e575, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:09,470 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 51b7d394-641f-41cc-b73a-d4929c89e575, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:11,579 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:11,580 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:11,613 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 74367b38-2e49-49e3-9501-b5e3b93f27aa, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:11,614 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 74367b38-2e49-49e3-9501-b5e3b93f27aa, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:11,615 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 74367b38-2e49-49e3-9501-b5e3b93f27aa, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:11,969 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:11,969 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:11,970 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, a9b129ab-124d-4a70-933f-c18cec7cd68a, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:11,971 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, a9b129ab-124d-4a70-933f-c18cec7cd68a, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:11,972 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, a9b129ab-124d-4a70-933f-c18cec7cd68a, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:12,484 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:12,485 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 1129888, 'BlobCount': 384}}
2025-12-23 14:55:12,486 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:12,486 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, e9909224-8942-4d41-93d2-40cea3ee3e44, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:12,487 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, e9909224-8942-4d41-93d2-40cea3ee3e44, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:12,489 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, e9909224-8942-4d41-93d2-40cea3ee3e44, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:14,524 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:14,525 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:14,526 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 5d321549-68cc-433c-adee-ae8c87e78af5, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:14,527 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 5d321549-68cc-433c-adee-ae8c87e78af5, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:14,529 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 5d321549-68cc-433c-adee-ae8c87e78af5, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:14,956 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:14,957 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:14,957 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 2daa04b8-bab1-4d57-a517-f58d6985b28c, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:14,959 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 2daa04b8-bab1-4d57-a517-f58d6985b28c, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:14,960 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 2daa04b8-bab1-4d57-a517-f58d6985b28c, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:15,377 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:15,377 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1507072, 'BlobCount': 512}}
2025-12-23 14:55:15,378 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:15,379 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, b1ef64e7-5cce-4e9a-9523-1ccd4b6382af, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:15,380 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, b1ef64e7-5cce-4e9a-9523-1ccd4b6382af, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:15,381 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, b1ef64e7-5cce-4e9a-9523-1ccd4b6382af, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:17,649 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:17,649 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:17,650 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, afa7dfb4-bd9a-45a4-84ec-3b5b49675576, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:17,651 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, afa7dfb4-bd9a-45a4-84ec-3b5b49675576, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:17,653 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, afa7dfb4-bd9a-45a4-84ec-3b5b49675576, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:17,970 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:17,970 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:17,971 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, a422958b-22ff-4e4d-8df8-fe7fde0103c7, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:17,972 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, a422958b-22ff-4e4d-8df8-fe7fde0103c7, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:17,974 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, a422958b-22ff-4e4d-8df8-fe7fde0103c7, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:18,583 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:18,584 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1883936, 'BlobCount': 640}}
2025-12-23 14:55:18,585 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:18,585 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 3e480e13-56b7-40e5-bf35-89a2657963b5, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:18,586 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 3e480e13-56b7-40e5-bf35-89a2657963b5, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:18,588 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 3e480e13-56b7-40e5-bf35-89a2657963b5, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:20,845 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:20,845 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:20,846 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 3d3550d1-e441-40fd-b151-1d7685725007, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:20,847 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 3d3550d1-e441-40fd-b151-1d7685725007, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:20,850 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 3d3550d1-e441-40fd-b151-1d7685725007, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:21,152 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:21,153 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:21,154 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 1b8a19b7-91d5-449c-b599-205c3810fb80, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:21,155 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 1b8a19b7-91d5-449c-b599-205c3810fb80, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:21,157 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 1b8a19b7-91d5-449c-b599-205c3810fb80, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:21,739 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:21,740 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 2261136, 'BlobCount': 768}}
2025-12-23 14:55:21,740 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:21,741 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, ca55a3f0-e76c-4ed5-806d-49f1df027e5e, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:21,742 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, ca55a3f0-e76c-4ed5-806d-49f1df027e5e, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:21,743 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, ca55a3f0-e76c-4ed5-806d-49f1df027e5e, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:24,156 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:24,157 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:24,157 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 876c6d53-f262-4310-af00-43d4830bbd74, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:24,158 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 876c6d53-f262-4310-af00-43d4830bbd74, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:24,160 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 876c6d53-f262-4310-af00-43d4830bbd74, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:24,672 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:24,673 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:24,673 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 0eb10c0c-9979-414a-8fc5-d1b9a0a22287, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:24,674 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 0eb10c0c-9979-414a-8fc5-d1b9a0a22287, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:24,676 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 0eb10c0c-9979-414a-8fc5-d1b9a0a22287, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:25,734 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:25,734 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2638112, 'BlobCount': 896}}
2025-12-23 14:55:25,735 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:25,735 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 44c66e2e-e4f0-4fdc-912c-fc6f4834bccc, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:25,736 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 44c66e2e-e4f0-4fdc-912c-fc6f4834bccc, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:25,738 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 44c66e2e-e4f0-4fdc-912c-fc6f4834bccc, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:28,456 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:28,457 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:28,458 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 1ced7546-d23d-4568-8e02-5030dd06e013, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:28,459 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 1ced7546-d23d-4568-8e02-5030dd06e013, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:28,460 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 1ced7546-d23d-4568-8e02-5030dd06e013, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:28,720 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:28,721 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:28,722 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 08d8324f-0d4e-44d4-87dc-78b15ac4fd2f, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:28,723 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 08d8324f-0d4e-44d4-87dc-78b15ac4fd2f, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:28,724 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 08d8324f-0d4e-44d4-87dc-78b15ac4fd2f, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:29,315 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:29,316 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 3015280, 'BlobCount': 1024}}
2025-12-23 14:55:29,316 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:29,317 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 9ae9da53-f78c-48c3-bb9b-e4ad1acab746, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:29,318 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 9ae9da53-f78c-48c3-bb9b-e4ad1acab746, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:29,319 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 9ae9da53-f78c-48c3-bb9b-e4ad1acab746, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:30,435 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.50224  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 14:55:30,435 - DEBUG - ydb.connection.50224  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 14:55:30,456 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.50224  - context_resolve: Resolving endpoints for database /Root
2025-12-23 14:55:30,457 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ListEndpoints, b7a6c399-fb61-4afd-a7bd-3bff1459ddbe, localhost:1516): creating call state
2025-12-23 14:55:30,458 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ListEndpoints, b7a6c399-fb61-4afd-a7bd-3bff1459ddbe, localhost:1516): request = { database: "/Root" }
2025-12-23 14:55:30,466 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ListEndpoints, b7a6c399-fb61-4afd-a7bd-3bff1459ddbe, localhost:1516): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-hclevy2dda.auto.internal" port: 1516 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-23 14:55:30,467 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.50224  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-hclevy2dda.auto.internal:1516, location 1, ssl: False>]>
2025-12-23 14:55:30,468 - DEBUG - ydb.connection.50224  - close: Closing channel for endpoint localhost:1516
2025-12-23 14:55:31,844 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:31,845 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:31,845 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 6b2dde4a-691e-4ecf-b891-79ef642c0f82, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:31,846 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 6b2dde4a-691e-4ecf-b891-79ef642c0f82, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:31,895 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 6b2dde4a-691e-4ecf-b891-79ef642c0f82, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:32,178 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:32,179 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:32,179 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 69e680d5-7126-4e05-a779-7109dc43d01c, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:32,180 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 69e680d5-7126-4e05-a779-7109dc43d01c, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:32,182 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 69e680d5-7126-4e05-a779-7109dc43d01c, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:32,880 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:32,907 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 3392288, 'BlobCount': 1152}}
2025-12-23 14:55:32,908 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:32,909 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 0192e0bd-6e6d-469f-96db-252dc74feb38, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:32,909 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 0192e0bd-6e6d-469f-96db-252dc74feb38, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:32,911 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 0192e0bd-6e6d-469f-96db-252dc74feb38, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:34,981 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:34,982 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:34,982 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 4c73e5bf-9739-4f2d-8d02-6282c88255ee, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:34,983 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 4c73e5bf-9739-4f2d-8d02-6282c88255ee, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:34,985 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 4c73e5bf-9739-4f2d-8d02-6282c88255ee, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:35,268 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:35,269 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:35,270 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 7c6f4636-37e1-4440-9b69-eaaccf978f8f, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:35,271 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 7c6f4636-37e1-4440-9b69-eaaccf978f8f, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:35,273 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 7c6f4636-37e1-4440-9b69-eaaccf978f8f, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:36,163 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:36,164 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_11_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3769600, 'BlobCount': 1280}}
2025-12-23 14:55:36,165 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:36,166 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 53f7e0c4-46a3-4196-bc06-0f12ea6744ca, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:36,167 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 53f7e0c4-46a3-4196-bc06-0f12ea6744ca, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_11_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:36,168 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 53f7e0c4-46a3-4196-bc06-0f12ea6744ca, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:37,719 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:37,720 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:37,721 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, ebd35a0a-fe19-4cd5-838c-78c770a06dba, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:37,722 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, ebd35a0a-fe19-4cd5-838c-78c770a06dba, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:37,724 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, ebd35a0a-fe19-4cd5-838c-78c770a06dba, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:38,068 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:38,069 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:38,070 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, f2e3498e-a52d-4c57-9894-46f03f627797, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:38,071 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, f2e3498e-a52d-4c57-9894-46f03f627797, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:38,072 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, f2e3498e-a52d-4c57-9894-46f03f627797, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:38,696 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:38,697 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:38,698 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 29ed811a-7579-47b3-b093-1f3be47cd95d, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:38,699 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 29ed811a-7579-47b3-b093-1f3be47cd95d, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:38,700 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 29ed811a-7579-47b3-b093-1f3be47cd95d, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:42,982 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:53,059 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:53,060 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 5f26a39d-05d2-4672-9546-45ec1f5a6047, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:53,061 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 5f26a39d-05d2-4672-9546-45ec1f5a6047, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:53,062 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 5f26a39d-05d2-4672-9546-45ec1f5a6047, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:56,464 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:56,498 - INFO - root.50224  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1884800)
2025-12-23 14:55:56,499 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:56,499 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, fd271990-c06d-4110-9a60-ad98b8345fab, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:56,500 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, fd271990-c06d-4110-9a60-ad98b8345fab, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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_11_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:56,502 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, fd271990-c06d-4110-9a60-ad98b8345fab, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:55:56,781 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:56,782 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:55:56,783 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, be87f074-e005-4344-940f-94b442c40d98, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:55:56,783 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, be87f074-e005-4344-940f-94b442c40d98, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:55:56,785 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, be87f074-e005-4344-940f-94b442c40d98, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:56:00,135 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:56:10,168 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:56:10,188 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, cbba2e15-11ff-482e-9f9c-ed6875705afb, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:56:10,188 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, cbba2e15-11ff-482e-9f9c-ed6875705afb, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_11_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:56:10,190 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, cbba2e15-11ff-482e-9f9c-ed6875705afb, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:56:10,890 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:56:10,895 - INFO - root.50224  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_11_compression`, volumes `value` (8000000, 1465344)
2025-12-23 14:56:10,895 - INFO - root.50224  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_11_compression` 8009216 / 1465344: 5.46575821104123
2025-12-23 14:56:10,915 - INFO - ya.test.50224  - pytest_runtest_teardown: Test teardown
2025-12-23 14:56:10,917 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(DeleteSession, 1a8afc59-e50f-4a26-8dd2-c0290a0936ba, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:56:10,917 - DEBUG - ydb.connection.50224  - _log_request: RpcState(DeleteSession, 1a8afc59-e50f-4a26-8dd2-c0290a0936ba, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" }
2025-12-23 14:56:10,928 - DEBUG - ydb.connection.50224  - _log_response: RpcState(DeleteSession, 1a8afc59-e50f-4a26-8dd2-c0290a0936ba, ghrun-hclevy2dda.auto.internal:1516): response = { status: SUCCESS }
2025-12-23 14:56:10,974 - DEBUG - ydb.query.pool.50224  - stop: All session were deleted.
2025-12-23 14:56:10,975 - DEBUG - ydb.topic.50224  - close: Close topic client
2025-12-23 14:56:10,975 - DEBUG - ydb.topic.50224  - close: Topic client was closed
2025-12-23 14:56:10,976 - DEBUG - ydb.connection.50224  - close: Closing channel for endpoint localhost:1516
2025-12-23 14:56:10,976 - DEBUG - ydb.connection.50224  - close: Closing channel for endpoint ghrun-hclevy2dda.auto.internal:1516
2025-12-23 14:56:10,980 - INFO - ydb.pool.Discovery.50224  - run: Successfully terminated discovery process
2025-12-23 14:56:10,977 - DEBUG - ydb.connection.50224  - _rpc_error_handler: RpcState(AttachSession, 71f1f0e6-b390-43f6-9ab9-e97308b21f19, ghrun-hclevy2dda.auto.internal:1516): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-23 14:56:27,996 - INFO - ydb.tests.library.harness.kikimr_runner.50224  - stop: Stopped node localhost:1516/1
2025-12-23 14:56:28,015 - DEBUG - library.python.filelock.50224  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6079
2025-12-23 14:56:28,016 - DEBUG - library.python.filelock.50224  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61197
2025-12-23 14:56:28,016 - DEBUG - library.python.filelock.50224  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14810
2025-12-23 14:56:28,017 - DEBUG - library.python.filelock.50224  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1516
2025-12-23 14:56:28,018 - DEBUG - library.python.filelock.50224  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16707
