2025-12-23 15:39:03,303 - INFO - ya.test.674953  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:39:03,303 - INFO - ya.test.674953  - pytest_runtest_setup: test_all_supported_compression[zstd_5_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 5]
2025-12-23 15:39:03,303 - INFO - ya.test.674953  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:39:03,304 - INFO - ya.test.674953  - pytest_runtest_setup: Test setup
2025-12-23 15:39:03,306 - INFO - ya.test.674953  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_5_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 5])
2025-12-23 15:39:03,307 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:03,307 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 24ca4b35-94e3-45e5-99aa-d92175d3d08a, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:03,308 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 24ca4b35-94e3-45e5-99aa-d92175d3d08a, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_5_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 5)\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 15:39:03,310 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 24ca4b35-94e3-45e5-99aa-d92175d3d08a, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:06,924 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:06,925 - INFO - __tests__.alter_compression.674953  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_5_compression created
2025-12-23 15:39:06,926 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:06,926 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, b24d7dc9-f99d-4a0f-bbd9-06630ccac775, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:06,927 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, b24d7dc9-f99d-4a0f-bbd9-06630ccac775, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_5_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:06,929 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, b24d7dc9-f99d-4a0f-bbd9-06630ccac775, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:07,856 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:07,857 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:07,857 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 91bebe51-5ca2-4315-84e0-a5c3bb70ff90, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:07,858 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 91bebe51-5ca2-4315-84e0-a5c3bb70ff90, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:07,860 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 91bebe51-5ca2-4315-84e0-a5c3bb70ff90, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:09,569 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:09,570 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:09,571 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 9d6d797b-3938-4a60-9b09-ef7f8185e8aa, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:09,572 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 9d6d797b-3938-4a60-9b09-ef7f8185e8aa, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:09,573 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 9d6d797b-3938-4a60-9b09-ef7f8185e8aa, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:10,606 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:10,607 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:10,608 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 2b0731f7-1273-48f7-8c3e-ddfc82e5f6eb, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:10,608 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 2b0731f7-1273-48f7-8c3e-ddfc82e5f6eb, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:10,610 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 2b0731f7-1273-48f7-8c3e-ddfc82e5f6eb, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:13,013 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:13,013 - INFO - __tests__.alter_compression.674953  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318064, 'BlobCount': 128}}
2025-12-23 15:39:13,014 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:13,014 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, f90010d6-8131-468f-8980-d190862463c6, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:13,015 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, f90010d6-8131-468f-8980-d190862463c6, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:13,016 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, f90010d6-8131-468f-8980-d190862463c6, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:14,304 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:14,305 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:14,306 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 8e5f70f0-61aa-41bc-84cb-a51692f74419, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:14,307 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 8e5f70f0-61aa-41bc-84cb-a51692f74419, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:14,308 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 8e5f70f0-61aa-41bc-84cb-a51692f74419, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:14,718 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:14,719 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:14,719 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, abcf7fc5-df2a-4e1d-a429-ad12b9052de2, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:14,720 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, abcf7fc5-df2a-4e1d-a429-ad12b9052de2, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:14,721 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, abcf7fc5-df2a-4e1d-a429-ad12b9052de2, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:15,305 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:15,305 - INFO - __tests__.alter_compression.674953  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637360, 'BlobCount': 256}}
2025-12-23 15:39:15,306 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:15,306 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 9e628bd2-06e3-454b-bad7-8a880d8f652e, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:15,307 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 9e628bd2-06e3-454b-bad7-8a880d8f652e, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:15,309 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 9e628bd2-06e3-454b-bad7-8a880d8f652e, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:16,596 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:16,597 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:16,598 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 52b7aacf-d5a9-4d16-9a3c-cc0f8de04c04, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:16,599 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 52b7aacf-d5a9-4d16-9a3c-cc0f8de04c04, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:16,601 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 52b7aacf-d5a9-4d16-9a3c-cc0f8de04c04, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:16,984 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:16,984 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:16,985 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 9f5b6d96-4c6d-47eb-a07d-6ebf84ca641c, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:16,986 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 9f5b6d96-4c6d-47eb-a07d-6ebf84ca641c, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:16,987 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 9f5b6d96-4c6d-47eb-a07d-6ebf84ca641c, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:17,415 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:17,416 - INFO - __tests__.alter_compression.674953  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956400, 'BlobCount': 384}}
2025-12-23 15:39:17,417 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:17,417 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 1e5f57c2-b9a7-4ba0-be01-ee2d684e16e6, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:17,418 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 1e5f57c2-b9a7-4ba0-be01-ee2d684e16e6, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:17,419 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 1e5f57c2-b9a7-4ba0-be01-ee2d684e16e6, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:18,628 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:18,629 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:18,630 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 7f2493dd-b981-46cc-acee-ec21ca355376, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:18,631 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 7f2493dd-b981-46cc-acee-ec21ca355376, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:18,632 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 7f2493dd-b981-46cc-acee-ec21ca355376, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:18,928 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:18,929 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:18,930 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 9fc5018e-4b72-47ec-b185-a1c5435ce539, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:18,932 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 9fc5018e-4b72-47ec-b185-a1c5435ce539, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:18,933 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 9fc5018e-4b72-47ec-b185-a1c5435ce539, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:19,382 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:19,382 - INFO - __tests__.alter_compression.674953  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275760, 'BlobCount': 512}}
2025-12-23 15:39:19,383 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:19,383 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, d454bec5-a40f-4538-be0e-3b1fe15cf757, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:19,384 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, d454bec5-a40f-4538-be0e-3b1fe15cf757, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:19,386 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, d454bec5-a40f-4538-be0e-3b1fe15cf757, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:20,778 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:20,778 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:20,779 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, d2344353-ca56-46bf-b8af-34dd3b4d93f3, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:20,780 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, d2344353-ca56-46bf-b8af-34dd3b4d93f3, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:20,781 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, d2344353-ca56-46bf-b8af-34dd3b4d93f3, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:21,072 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:21,072 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:21,073 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 1bd4121c-4651-4b19-843e-7255b1161faf, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:21,074 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 1bd4121c-4651-4b19-843e-7255b1161faf, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:21,075 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 1bd4121c-4651-4b19-843e-7255b1161faf, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:21,580 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:21,580 - INFO - __tests__.alter_compression.674953  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594720, 'BlobCount': 640}}
2025-12-23 15:39:21,581 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:21,581 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, fb93d63a-b8cc-4360-992b-bd8faaa2bad0, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:21,582 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, fb93d63a-b8cc-4360-992b-bd8faaa2bad0, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:21,584 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, fb93d63a-b8cc-4360-992b-bd8faaa2bad0, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:22,837 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:22,838 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:22,839 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, d6328312-b42d-484a-9f3f-369d8d32efb8, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:22,840 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, d6328312-b42d-484a-9f3f-369d8d32efb8, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:22,842 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, d6328312-b42d-484a-9f3f-369d8d32efb8, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:23,126 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:23,127 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:23,128 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, e724fde7-6c72-4a43-a3f2-77413e7bbe21, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:23,129 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, e724fde7-6c72-4a43-a3f2-77413e7bbe21, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:23,132 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, e724fde7-6c72-4a43-a3f2-77413e7bbe21, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:23,744 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:23,745 - INFO - __tests__.alter_compression.674953  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913904, 'BlobCount': 768}}
2025-12-23 15:39:23,745 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:23,746 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, dc95763a-9292-42b0-aed5-9b51912ced05, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:23,747 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, dc95763a-9292-42b0-aed5-9b51912ced05, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:23,749 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, dc95763a-9292-42b0-aed5-9b51912ced05, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:24,991 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:24,991 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:24,992 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 8e0f5bc6-2def-44f0-a9f2-a16062ee83a4, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:24,993 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 8e0f5bc6-2def-44f0-a9f2-a16062ee83a4, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:24,994 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 8e0f5bc6-2def-44f0-a9f2-a16062ee83a4, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:25,308 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:25,309 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:25,309 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 2c410f5f-1d23-4ed9-8b4a-d9ee6d78fd82, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:25,310 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 2c410f5f-1d23-4ed9-8b4a-d9ee6d78fd82, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:25,312 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 2c410f5f-1d23-4ed9-8b4a-d9ee6d78fd82, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:25,961 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:25,962 - INFO - __tests__.alter_compression.674953  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233040, 'BlobCount': 896}}
2025-12-23 15:39:25,963 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:25,963 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 0464d530-e0df-43ef-b1fc-2267c009f839, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:25,964 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 0464d530-e0df-43ef-b1fc-2267c009f839, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:25,966 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 0464d530-e0df-43ef-b1fc-2267c009f839, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:27,232 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:27,233 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:27,234 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, a40c6a97-b235-402c-beb6-5c6c5e9664bc, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:27,235 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, a40c6a97-b235-402c-beb6-5c6c5e9664bc, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:27,240 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, a40c6a97-b235-402c-beb6-5c6c5e9664bc, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:27,533 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:27,534 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:27,534 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, b897ea80-d0ba-4cec-9728-e3a9b991e6e9, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:27,535 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, b897ea80-d0ba-4cec-9728-e3a9b991e6e9, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:27,545 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, b897ea80-d0ba-4cec-9728-e3a9b991e6e9, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:28,032 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.674953  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:39:28,033 - DEBUG - ydb.connection.674953  - 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 15:39:28,074 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.674953  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:39:28,075 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ListEndpoints, 88dd86e5-82e8-4af8-bd6d-953aa0b61290, localhost:19038): creating call state
2025-12-23 15:39:28,075 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ListEndpoints, 88dd86e5-82e8-4af8-bd6d-953aa0b61290, localhost:19038): request = { database: "/Root" }
2025-12-23 15:39:28,112 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ListEndpoints, 88dd86e5-82e8-4af8-bd6d-953aa0b61290, localhost:19038): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-icmf7pzugu.auto.internal" port: 19038 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 15:39:28,113 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.674953  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-icmf7pzugu.auto.internal:19038, location 1, ssl: False>]>
2025-12-23 15:39:28,114 - DEBUG - ydb.connection.674953  - close: Closing channel for endpoint localhost:19038
2025-12-23 15:39:28,201 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:28,202 - INFO - __tests__.alter_compression.674953  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552528, 'BlobCount': 1024}}
2025-12-23 15:39:28,202 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:28,203 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, af286431-ce24-4d6a-b99a-4aa009c4927f, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:28,204 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, af286431-ce24-4d6a-b99a-4aa009c4927f, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:28,210 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, af286431-ce24-4d6a-b99a-4aa009c4927f, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:29,525 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:29,526 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:29,527 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, e989069f-0104-44bb-9368-ed3582cd164e, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:29,528 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, e989069f-0104-44bb-9368-ed3582cd164e, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:29,529 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, e989069f-0104-44bb-9368-ed3582cd164e, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:29,872 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:29,873 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:29,874 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, d101893b-4434-452a-a0ad-60b93e2bc4c0, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:29,875 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, d101893b-4434-452a-a0ad-60b93e2bc4c0, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:29,876 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, d101893b-4434-452a-a0ad-60b93e2bc4c0, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:30,681 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:30,681 - INFO - __tests__.alter_compression.674953  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871536, 'BlobCount': 1152}}
2025-12-23 15:39:30,682 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:30,682 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 5623e628-288e-4e9d-b6e6-801d3c59a8b2, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:30,683 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 5623e628-288e-4e9d-b6e6-801d3c59a8b2, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:30,686 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 5623e628-288e-4e9d-b6e6-801d3c59a8b2, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:31,811 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:31,812 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:31,812 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 882bdcbf-0dda-4208-b1f1-cfd57e1001d3, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:31,813 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 882bdcbf-0dda-4208-b1f1-cfd57e1001d3, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:31,815 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 882bdcbf-0dda-4208-b1f1-cfd57e1001d3, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:32,115 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:32,116 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:32,117 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 3b3f0f29-2694-49e3-b074-47f04b5368c4, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:32,118 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 3b3f0f29-2694-49e3-b074-47f04b5368c4, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:32,119 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 3b3f0f29-2694-49e3-b074-47f04b5368c4, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:32,762 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:32,763 - INFO - __tests__.alter_compression.674953  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_5_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191040, 'BlobCount': 1280}}
2025-12-23 15:39:32,764 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:32,764 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 9bb1aad3-9715-4943-94b9-9a3984994e3f, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:32,765 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 9bb1aad3-9715-4943-94b9-9a3984994e3f, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_5_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:32,767 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 9bb1aad3-9715-4943-94b9-9a3984994e3f, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:33,880 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:33,881 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:33,882 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 431f8339-7a3f-42f1-803b-191ffbb15e6e, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:33,883 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 431f8339-7a3f-42f1-803b-191ffbb15e6e, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:33,885 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 431f8339-7a3f-42f1-803b-191ffbb15e6e, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:34,182 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:34,183 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:34,184 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 424f1970-dffe-44ac-8f4a-42d513a9f73b, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:34,185 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 424f1970-dffe-44ac-8f4a-42d513a9f73b, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:34,187 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 424f1970-dffe-44ac-8f4a-42d513a9f73b, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:34,430 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:34,432 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:34,433 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 0aeed88a-6408-4821-9362-8b6d71a739f1, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:34,434 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 0aeed88a-6408-4821-9362-8b6d71a739f1, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_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 15:39:34,436 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 0aeed88a-6408-4821-9362-8b6d71a739f1, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:38,084 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:48,152 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:48,153 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 9c150dd1-069e-49c0-899b-614312cdfb05, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:48,154 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 9c150dd1-069e-49c0-899b-614312cdfb05, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_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 15:39:48,156 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 9c150dd1-069e-49c0-899b-614312cdfb05, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:51,536 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:51,574 - INFO - root.674953  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1595520)
2025-12-23 15:39:51,575 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:51,576 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, ad94ee27-3a50-42d2-bc32-4d92297d3771, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:51,576 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, ad94ee27-3a50-42d2-bc32-4d92297d3771, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" 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_5_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 15:39:51,577 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, ad94ee27-3a50-42d2-bc32-4d92297d3771, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:51,969 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:51,970 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:39:51,970 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 9db6d63d-3a1e-43d0-a640-7730ec985308, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:39:51,971 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 9db6d63d-3a1e-43d0-a640-7730ec985308, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_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 15:39:51,973 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 9db6d63d-3a1e-43d0-a640-7730ec985308, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:55,555 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:40:05,646 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:40:05,647 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, f5ef606d-a284-4444-ab78-767032bd1efb, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:40:05,648 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, f5ef606d-a284-4444-ab78-767032bd1efb, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_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 15:40:05,649 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, f5ef606d-a284-4444-ab78-767032bd1efb, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:08,867 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:40:18,907 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:40:18,907 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, b825d1b8-400f-422f-a92e-7f580caa0250, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:40:18,908 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, b825d1b8-400f-422f-a92e-7f580caa0250, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_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 15:40:18,909 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, b825d1b8-400f-422f-a92e-7f580caa0250, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:19,608 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:40:29,613 - DEBUG - ydb.query.pool.674953  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:40:29,614 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(ExecuteQuery, 15a7ba73-0abe-4351-bf40-0da5047ee556, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:40:29,615 - DEBUG - ydb.connection.674953  - _log_request: RpcState(ExecuteQuery, 15a7ba73-0abe-4351-bf40-0da5047ee556, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_5_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 15:40:29,617 - DEBUG - ydb.connection.674953  - _log_response: RpcState(ExecuteQuery, 15a7ba73-0abe-4351-bf40-0da5047ee556, ghrun-icmf7pzugu.auto.internal:19038): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:30,278 - DEBUG - ydb.query.pool.674953  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=
2025-12-23 15:40:30,282 - INFO - root.674953  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_5_compression`, volumes `value` (8000000, 1466976)
2025-12-23 15:40:30,283 - INFO - root.674953  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_5_compression` 8092160 / 1466976: 5.51621839757433
2025-12-23 15:40:30,285 - INFO - ya.test.674953  - pytest_runtest_teardown: Test teardown
2025-12-23 15:40:30,286 - DEBUG - ydb.connection.674953  - _prepare_call: RpcState(DeleteSession, 9080071c-89ea-4433-8018-c92bdbd6c5dc, ghrun-icmf7pzugu.auto.internal:19038): creating call state
2025-12-23 15:40:30,286 - DEBUG - ydb.connection.674953  - _log_request: RpcState(DeleteSession, 9080071c-89ea-4433-8018-c92bdbd6c5dc, ghrun-icmf7pzugu.auto.internal:19038): request = { session_id: "ydb://session/3?node_id=1&id=ZmJiZTY1OTItYTJlM2M0YmYtN2Y2MWU5MzgtOTVlYmZiYjE=" }
2025-12-23 15:40:30,290 - DEBUG - ydb.connection.674953  - _log_response: RpcState(DeleteSession, 9080071c-89ea-4433-8018-c92bdbd6c5dc, ghrun-icmf7pzugu.auto.internal:19038): response = { status: SUCCESS }
2025-12-23 15:40:30,291 - DEBUG - ydb.query.pool.674953  - stop: All session were deleted.
2025-12-23 15:40:30,292 - DEBUG - ydb.topic.674953  - close: Close topic client
2025-12-23 15:40:30,292 - DEBUG - ydb.topic.674953  - close: Topic client was closed
2025-12-23 15:40:30,293 - DEBUG - ydb.connection.674953  - close: Closing channel for endpoint localhost:19038
2025-12-23 15:40:30,294 - DEBUG - ydb.connection.674953  - close: Closing channel for endpoint ghrun-icmf7pzugu.auto.internal:19038
2025-12-23 15:40:30,299 - DEBUG - ydb.connection.674953  - _rpc_error_handler: RpcState(AttachSession, 77af4f8f-8a71-4869-9204-27e0cbe5cc41, ghrun-icmf7pzugu.auto.internal:19038): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-23 15:40:30,306 - INFO - ydb.pool.Discovery.674953  - run: Successfully terminated discovery process
2025-12-23 15:40:47,313 - INFO - ydb.tests.library.harness.kikimr_runner.674953  - stop: Stopped node localhost:19038/1
2025-12-23 15:40:47,314 - DEBUG - library.python.filelock.674953  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21784
2025-12-23 15:40:47,315 - DEBUG - library.python.filelock.674953  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27705
2025-12-23 15:40:47,315 - DEBUG - library.python.filelock.674953  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22187
2025-12-23 15:40:47,315 - DEBUG - library.python.filelock.674953  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19038
2025-12-23 15:40:47,316 - DEBUG - library.python.filelock.674953  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9761
