2025-12-04 20:55:29,813 - INFO - ya.test.489144  - pytest_runtest_setup: ####################################################################################################
2025-12-04 20:55:29,814 - INFO - ya.test.489144  - pytest_runtest_setup: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19]
2025-12-04 20:55:29,814 - INFO - ya.test.489144  - pytest_runtest_setup: ####################################################################################################
2025-12-04 20:55:29,814 - INFO - ya.test.489144  - pytest_runtest_setup: Test setup
2025-12-04 20:55:29,816 - INFO - ya.test.489144  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19])
2025-12-04 20:55:29,817 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:29,817 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 620dff65-a145-40fa-9a62-9730f91d55a3, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:29,818 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 620dff65-a145-40fa-9a62-9730f91d55a3, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_19_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 19)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:29,820 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 620dff65-a145-40fa-9a62-9730f91d55a3, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:32,833 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:32,834 - INFO - __tests__.alter_compression.489144  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_19_compression created
2025-12-04 20:55:32,834 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:32,835 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 6e27d6b0-5b31-4b2a-b2a8-a1b844f940c7, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:32,836 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 6e27d6b0-5b31-4b2a-b2a8-a1b844f940c7, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:32,837 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 6e27d6b0-5b31-4b2a-b2a8-a1b844f940c7, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:33,918 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:33,918 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:33,919 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, f1344ed2-917f-4cce-b35b-389ab012990f, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:33,920 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, f1344ed2-917f-4cce-b35b-389ab012990f, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:33,922 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, f1344ed2-917f-4cce-b35b-389ab012990f, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:37,184 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:37,184 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:37,185 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, e877955d-e692-4797-a03c-1a6e2c83e12e, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:37,186 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, e877955d-e692-4797-a03c-1a6e2c83e12e, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:37,187 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, e877955d-e692-4797-a03c-1a6e2c83e12e, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:38,009 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:38,009 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:38,010 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 00adaa6d-0a0d-488c-a842-f04108006e7a, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:38,011 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 00adaa6d-0a0d-488c-a842-f04108006e7a, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:38,012 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 00adaa6d-0a0d-488c-a842-f04108006e7a, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:39,532 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:39,532 - INFO - __tests__.alter_compression.489144  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-12-04 20:55:39,533 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:39,533 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, e684bec0-9b36-41df-be93-24ee0ac069a1, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:39,534 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, e684bec0-9b36-41df-be93-24ee0ac069a1, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:39,536 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, e684bec0-9b36-41df-be93-24ee0ac069a1, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:42,728 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:42,729 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:42,729 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, c841a30a-630a-4969-85e0-46813781d345, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:42,730 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, c841a30a-630a-4969-85e0-46813781d345, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:42,731 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, c841a30a-630a-4969-85e0-46813781d345, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:42,960 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:42,961 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:42,962 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 3675c229-99b4-41ef-9ee4-67a9c3e345fe, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:42,963 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 3675c229-99b4-41ef-9ee4-67a9c3e345fe, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:42,964 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 3675c229-99b4-41ef-9ee4-67a9c3e345fe, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:43,217 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:43,218 - INFO - __tests__.alter_compression.489144  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-12-04 20:55:43,218 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:43,219 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, aa6a7c6d-c9ac-4fc6-bea1-7824c5456bfa, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:43,220 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, aa6a7c6d-c9ac-4fc6-bea1-7824c5456bfa, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:43,221 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, aa6a7c6d-c9ac-4fc6-bea1-7824c5456bfa, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:45,717 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:45,717 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:45,718 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, cfaec24d-fe06-4e7d-b064-f5314109b52c, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:45,719 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, cfaec24d-fe06-4e7d-b064-f5314109b52c, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:45,720 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, cfaec24d-fe06-4e7d-b064-f5314109b52c, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:45,941 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:45,942 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:45,943 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, c5c8e4ee-f659-4301-8b02-b7872f394c6b, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:45,943 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, c5c8e4ee-f659-4301-8b02-b7872f394c6b, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:45,944 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, c5c8e4ee-f659-4301-8b02-b7872f394c6b, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:46,257 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:46,258 - INFO - __tests__.alter_compression.489144  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-12-04 20:55:46,258 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:46,259 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 60819ae0-3008-4841-affd-02b3df2a4cb4, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:46,260 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 60819ae0-3008-4841-affd-02b3df2a4cb4, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:46,261 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 60819ae0-3008-4841-affd-02b3df2a4cb4, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:48,968 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:48,969 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:48,969 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 5e91e9bc-5345-41a4-a251-709c46836abe, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:48,970 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 5e91e9bc-5345-41a4-a251-709c46836abe, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:48,971 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 5e91e9bc-5345-41a4-a251-709c46836abe, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:49,177 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:49,178 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:49,178 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 43d57431-71f9-4f4f-a573-7b0fbedef681, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:49,179 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 43d57431-71f9-4f4f-a573-7b0fbedef681, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:49,180 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 43d57431-71f9-4f4f-a573-7b0fbedef681, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:49,540 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:49,541 - INFO - __tests__.alter_compression.489144  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-12-04 20:55:49,541 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:49,542 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, f6e18c25-684c-4cb8-b222-4857073e172c, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:49,542 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, f6e18c25-684c-4cb8-b222-4857073e172c, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:49,543 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, f6e18c25-684c-4cb8-b222-4857073e172c, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:52,037 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:52,038 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:52,039 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, d49c3856-4376-445f-9295-7bb0ab9e2716, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:52,039 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, d49c3856-4376-445f-9295-7bb0ab9e2716, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:52,041 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, d49c3856-4376-445f-9295-7bb0ab9e2716, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:52,246 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:52,247 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:52,248 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, e258112d-318d-41f2-9ef6-5cbc0625b337, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:52,249 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, e258112d-318d-41f2-9ef6-5cbc0625b337, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:52,250 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, e258112d-318d-41f2-9ef6-5cbc0625b337, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:52,609 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:52,609 - INFO - __tests__.alter_compression.489144  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-12-04 20:55:52,610 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:52,610 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, d70a41df-76cc-4a0a-901c-a89990ca1c94, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:52,611 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, d70a41df-76cc-4a0a-901c-a89990ca1c94, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:52,613 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, d70a41df-76cc-4a0a-901c-a89990ca1c94, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:55,655 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:55,656 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:55,657 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 09564e0d-a82f-4085-8836-c888a9d29172, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:55,657 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 09564e0d-a82f-4085-8836-c888a9d29172, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:55,658 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 09564e0d-a82f-4085-8836-c888a9d29172, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:55,961 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:55,962 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:55,963 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, cac9b073-def8-4fb7-b71a-4da0c3bb65c4, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:55,963 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, cac9b073-def8-4fb7-b71a-4da0c3bb65c4, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:55,965 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, cac9b073-def8-4fb7-b71a-4da0c3bb65c4, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:56,370 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:56,370 - INFO - __tests__.alter_compression.489144  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-12-04 20:55:56,371 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:56,371 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 1d60c163-31e1-4d30-aed8-ed75da0851ff, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:56,372 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 1d60c163-31e1-4d30-aed8-ed75da0851ff, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:56,373 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 1d60c163-31e1-4d30-aed8-ed75da0851ff, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:55:58,692 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.489144  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 20:55:58,692 - DEBUG - ydb.connection.489144  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 20:55:58,703 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.489144  - context_resolve: Resolving endpoints for database /Root
2025-12-04 20:55:58,704 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ListEndpoints, df1d6d07-bd35-443e-83bf-53f3062bcef9, localhost:24442): creating call state
2025-12-04 20:55:58,705 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ListEndpoints, df1d6d07-bd35-443e-83bf-53f3062bcef9, localhost:24442): request = { database: "/Root" }
2025-12-04 20:55:58,709 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ListEndpoints, df1d6d07-bd35-443e-83bf-53f3062bcef9, localhost:24442): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-fryxcz52gi.auto.internal" port: 24442 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-04 20:55:58,710 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.489144  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-fryxcz52gi.auto.internal:24442, location 1, ssl: False>]>
2025-12-04 20:55:58,711 - DEBUG - ydb.connection.489144  - close: Closing channel for endpoint localhost:24442
2025-12-04 20:55:59,769 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:59,769 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:55:59,770 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, dd03ffe3-e2c3-4a4e-aa01-a2bd6cb24ed8, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:55:59,771 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, dd03ffe3-e2c3-4a4e-aa01-a2bd6cb24ed8, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:55:59,776 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, dd03ffe3-e2c3-4a4e-aa01-a2bd6cb24ed8, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:00,046 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:00,046 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:00,047 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 6ae16e1e-17c8-4104-92d9-47a7f2a33809, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:00,048 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 6ae16e1e-17c8-4104-92d9-47a7f2a33809, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:00,050 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 6ae16e1e-17c8-4104-92d9-47a7f2a33809, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:00,529 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:00,529 - INFO - __tests__.alter_compression.489144  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-12-04 20:56:00,530 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:00,531 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 32c8eeff-eea2-4660-85e6-74d29e1322a2, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:00,532 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 32c8eeff-eea2-4660-85e6-74d29e1322a2, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:00,533 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 32c8eeff-eea2-4660-85e6-74d29e1322a2, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:03,993 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:03,994 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:03,995 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, bd84e941-df4c-48d5-930c-8e7cf0d16167, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:03,995 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, bd84e941-df4c-48d5-930c-8e7cf0d16167, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:03,997 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, bd84e941-df4c-48d5-930c-8e7cf0d16167, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:04,288 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:04,289 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:04,290 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, a5ff588c-70db-4ae4-843d-2e9964042466, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:04,290 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, a5ff588c-70db-4ae4-843d-2e9964042466, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:04,292 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, a5ff588c-70db-4ae4-843d-2e9964042466, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:04,880 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:04,881 - INFO - __tests__.alter_compression.489144  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-12-04 20:56:04,881 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:04,882 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 5f872c55-3b46-460f-a728-f2e97cf401d5, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:04,883 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 5f872c55-3b46-460f-a728-f2e97cf401d5, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:04,884 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 5f872c55-3b46-460f-a728-f2e97cf401d5, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:08,812 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:08,813 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:08,813 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 9a29a453-11a0-4288-85a2-5f5e077d0172, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:08,814 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 9a29a453-11a0-4288-85a2-5f5e077d0172, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:08,816 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 9a29a453-11a0-4288-85a2-5f5e077d0172, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:09,100 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:09,101 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:09,102 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 024923b5-7f54-4a54-9874-6586a089ef95, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:09,103 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 024923b5-7f54-4a54-9874-6586a089ef95, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:09,108 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 024923b5-7f54-4a54-9874-6586a089ef95, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:09,835 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:09,836 - INFO - __tests__.alter_compression.489144  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-12-04 20:56:09,836 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:09,837 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 87614e8d-8654-427d-a8b8-79f84526f0d9, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:09,838 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 87614e8d-8654-427d-a8b8-79f84526f0d9, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:09,841 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 87614e8d-8654-427d-a8b8-79f84526f0d9, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:14,524 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:14,525 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:14,526 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 5ca18682-8595-4702-9954-3bbb52f12b15, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:14,527 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 5ca18682-8595-4702-9954-3bbb52f12b15, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:14,528 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 5ca18682-8595-4702-9954-3bbb52f12b15, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:14,807 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:14,807 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:14,808 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, bc70441d-5bf1-4a65-ab9b-bd21579541a4, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:14,809 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, bc70441d-5bf1-4a65-ab9b-bd21579541a4, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:14,810 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, bc70441d-5bf1-4a65-ab9b-bd21579541a4, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:15,490 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:15,491 - INFO - __tests__.alter_compression.489144  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-12-04 20:56:15,492 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:15,492 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, c7322e38-6c72-4cf7-851e-1d042001fcd6, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:15,493 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, c7322e38-6c72-4cf7-851e-1d042001fcd6, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:15,495 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, c7322e38-6c72-4cf7-851e-1d042001fcd6, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:16,916 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:16,917 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:16,918 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 52938772-64d8-4598-98bb-c63c98123ad0, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:16,919 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 52938772-64d8-4598-98bb-c63c98123ad0, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:16,920 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 52938772-64d8-4598-98bb-c63c98123ad0, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:17,432 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:17,433 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:17,434 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 3776c811-c723-4d5c-8026-15b34d3fe7ed, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:17,435 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 3776c811-c723-4d5c-8026-15b34d3fe7ed, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:17,441 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 3776c811-c723-4d5c-8026-15b34d3fe7ed, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:17,836 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:17,837 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:17,838 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 259ad305-2526-4744-bb65-c01a1872c210, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:17,839 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 259ad305-2526-4744-bb65-c01a1872c210, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:17,840 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 259ad305-2526-4744-bb65-c01a1872c210, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:21,888 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:31,959 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:31,960 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, b5b6a031-95b0-463e-87b8-a17134c790b8, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:31,961 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, b5b6a031-95b0-463e-87b8-a17134c790b8, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:31,962 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, b5b6a031-95b0-463e-87b8-a17134c790b8, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:35,226 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:35,257 - INFO - root.489144  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1595704)
2025-12-04 20:56:35,258 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:35,259 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 71dd7d8a-d845-4898-a065-61ef53cd58ea, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:35,260 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 71dd7d8a-d845-4898-a065-61ef53cd58ea, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" 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_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:35,261 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 71dd7d8a-d845-4898-a065-61ef53cd58ea, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:35,611 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:35,612 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:35,613 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, 0ae430f6-c6ad-4173-aca4-9427eb005abc, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:35,613 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, 0ae430f6-c6ad-4173-aca4-9427eb005abc, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:35,615 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, 0ae430f6-c6ad-4173-aca4-9427eb005abc, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:38,452 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:48,480 - DEBUG - ydb.query.pool.489144  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:48,481 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(ExecuteQuery, a6bef8ea-1bcf-44c2-a18e-11511340c863, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:48,482 - DEBUG - ydb.connection.489144  - _log_request: RpcState(ExecuteQuery, a6bef8ea-1bcf-44c2-a18e-11511340c863, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 20:56:48,483 - DEBUG - ydb.connection.489144  - _log_response: RpcState(ExecuteQuery, a6bef8ea-1bcf-44c2-a18e-11511340c863, ghrun-fryxcz52gi.auto.internal:24442): response = { <_MultiThreadedRendezvous object> }
2025-12-04 20:56:50,303 - DEBUG - ydb.query.pool.489144  - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx
2025-12-04 20:56:50,315 - INFO - root.489144  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1508616)
2025-12-04 20:56:50,316 - INFO - root.489144  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_19_compression` 8092160 / 1508616: 5.363962731404148
2025-12-04 20:56:50,318 - INFO - ya.test.489144  - pytest_runtest_teardown: Test teardown
2025-12-04 20:56:50,319 - DEBUG - ydb.connection.489144  - _prepare_call: RpcState(DeleteSession, 0f153414-b218-4adb-beff-32e7fd0241f4, ghrun-fryxcz52gi.auto.internal:24442): creating call state
2025-12-04 20:56:50,320 - DEBUG - ydb.connection.489144  - _log_request: RpcState(DeleteSession, 0f153414-b218-4adb-beff-32e7fd0241f4, ghrun-fryxcz52gi.auto.internal:24442): request = { session_id: "ydb://session/3?node_id=1&id=Y2FhMzk5ODktNWM1OWE1NzUtMWFkYTgzLTIzY2VlYTUx" }
2025-12-04 20:56:50,325 - DEBUG - ydb.connection.489144  - _log_response: RpcState(DeleteSession, 0f153414-b218-4adb-beff-32e7fd0241f4, ghrun-fryxcz52gi.auto.internal:24442): response = { status: SUCCESS }
2025-12-04 20:56:50,327 - DEBUG - ydb.query.pool.489144  - stop: All session were deleted.
2025-12-04 20:56:50,328 - DEBUG - ydb.topic.489144  - close: Close topic client
2025-12-04 20:56:50,328 - DEBUG - ydb.topic.489144  - close: Topic client was closed
2025-12-04 20:56:50,328 - DEBUG - ydb.connection.489144  - close: Closing channel for endpoint localhost:24442
2025-12-04 20:56:50,333 - DEBUG - ydb.connection.489144  - close: Closing channel for endpoint ghrun-fryxcz52gi.auto.internal:24442
2025-12-04 20:56:50,335 - INFO - ydb.pool.Discovery.489144  - run: Successfully terminated discovery process
2025-12-04 20:56:50,340 - DEBUG - ydb.connection.489144  - _rpc_error_handler: RpcState(AttachSession, 11e2eb8c-8dee-4cd0-bba9-164414d1fb13, ghrun-fryxcz52gi.auto.internal:24442): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-04 20:57:12,362 - INFO - ydb.tests.library.harness.kikimr_runner.489144  - stop: Stopped node localhost:24442/1
2025-12-04 20:57:12,366 - DEBUG - library.python.filelock.489144  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11067
2025-12-04 20:57:12,366 - DEBUG - library.python.filelock.489144  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29454
2025-12-04 20:57:12,367 - DEBUG - library.python.filelock.489144  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31022
2025-12-04 20:57:12,367 - DEBUG - library.python.filelock.489144  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24442
2025-12-04 20:57:12,367 - DEBUG - library.python.filelock.489144  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24929
