2025-11-21 12:12:54,541 - INFO - ya.test.1714338  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:12:54,541 - INFO - ya.test.1714338  - pytest_runtest_setup: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14]
2025-11-21 12:12:54,542 - INFO - ya.test.1714338  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:12:54,542 - INFO - ya.test.1714338  - pytest_runtest_setup: Test setup
2025-11-21 12:12:54,548 - INFO - ya.test.1714338  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14])
2025-11-21 12:12:54,549 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:12:54,550 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, dc757a37-932b-48ef-b737-946add5d7618, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:12:54,551 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, dc757a37-932b-48ef-b737-946add5d7618, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_14_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 14)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:54,556 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, dc757a37-932b-48ef-b737-946add5d7618, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:58,262 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:12:58,263 - INFO - __tests__.alter_compression.1714338  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_14_compression created
2025-11-21 12:12:58,263 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:12:58,264 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 9c38d573-1134-4e0c-b72c-50966c42fc98, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:12:58,264 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 9c38d573-1134-4e0c-b72c-50966c42fc98, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:58,265 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 9c38d573-1134-4e0c-b72c-50966c42fc98, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:12:59,401 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:12:59,402 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:12:59,402 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, d7bb33fe-81fb-4d29-bf2d-8fafd9f3fec4, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:12:59,403 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, d7bb33fe-81fb-4d29-bf2d-8fafd9f3fec4, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:12:59,405 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, d7bb33fe-81fb-4d29-bf2d-8fafd9f3fec4, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:03,401 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:03,401 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:03,402 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 1371d21e-1562-4de7-8417-970336ccc973, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:03,403 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 1371d21e-1562-4de7-8417-970336ccc973, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:03,404 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 1371d21e-1562-4de7-8417-970336ccc973, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:04,924 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:04,925 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:04,925 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 12901f2f-511f-4cf1-b0b8-ece584416397, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:04,926 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 12901f2f-511f-4cf1-b0b8-ece584416397, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:04,928 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 12901f2f-511f-4cf1-b0b8-ece584416397, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:06,935 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714338  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 12:13:06,936 - DEBUG - ydb.connection.1714338  - 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-11-21 12:13:06,975 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714338  - context_resolve: Resolving endpoints for database /Root
2025-11-21 12:13:06,976 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ListEndpoints, 3b9f454f-f561-444c-a152-71450d30cd96, localhost:13711): creating call state
2025-11-21 12:13:06,977 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ListEndpoints, 3b9f454f-f561-444c-a152-71450d30cd96, localhost:13711): request = { database: "/Root" }
2025-11-21 12:13:06,985 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ListEndpoints, 3b9f454f-f561-444c-a152-71450d30cd96, localhost:13711): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ts5cnd6jre.auto.internal" port: 13711 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-11-21 12:13:06,986 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714338  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ts5cnd6jre.auto.internal:13711, location 1, ssl: False>]>
2025-11-21 12:13:06,987 - DEBUG - ydb.connection.1714338  - close: Closing channel for endpoint localhost:13711
2025-11-21 12:13:07,448 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:07,448 - INFO - __tests__.alter_compression.1714338  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-11-21 12:13:07,449 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:07,449 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, c1c1f593-1070-46d8-99cf-770343a4f199, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:07,450 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, c1c1f593-1070-46d8-99cf-770343a4f199, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:07,456 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, c1c1f593-1070-46d8-99cf-770343a4f199, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:10,941 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:10,942 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:10,942 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 68e51087-a013-4181-871e-33c8d8fc4bc9, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:10,943 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 68e51087-a013-4181-871e-33c8d8fc4bc9, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:10,946 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 68e51087-a013-4181-871e-33c8d8fc4bc9, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:11,427 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:11,427 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:11,428 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 8de8fbfe-9f50-474b-b98a-25496b4f6c97, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:11,429 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 8de8fbfe-9f50-474b-b98a-25496b4f6c97, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:11,431 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 8de8fbfe-9f50-474b-b98a-25496b4f6c97, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:12,011 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:12,012 - INFO - __tests__.alter_compression.1714338  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-11-21 12:13:12,047 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:12,048 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, f0f9d93c-9716-488a-9844-840cd78524ae, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:12,049 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, f0f9d93c-9716-488a-9844-840cd78524ae, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:12,050 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, f0f9d93c-9716-488a-9844-840cd78524ae, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:15,759 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:15,760 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:15,761 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, a17decef-bc26-44bf-9d3a-89d1268af13d, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:15,762 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, a17decef-bc26-44bf-9d3a-89d1268af13d, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:15,764 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, a17decef-bc26-44bf-9d3a-89d1268af13d, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:16,313 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:16,314 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:16,315 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, f784d804-cad5-48f4-9b4c-10ff307b49f7, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:16,316 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, f784d804-cad5-48f4-9b4c-10ff307b49f7, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:16,317 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, f784d804-cad5-48f4-9b4c-10ff307b49f7, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:16,936 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:16,937 - INFO - __tests__.alter_compression.1714338  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-11-21 12:13:16,937 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:16,938 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, a1bc0771-dc88-4fad-a908-02f381694c47, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:16,939 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, a1bc0771-dc88-4fad-a908-02f381694c47, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:16,943 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, a1bc0771-dc88-4fad-a908-02f381694c47, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:20,544 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:20,544 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:20,545 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, c3830207-62d4-456a-83d7-e078d16f1343, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:20,546 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, c3830207-62d4-456a-83d7-e078d16f1343, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:20,548 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, c3830207-62d4-456a-83d7-e078d16f1343, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:21,036 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:21,037 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:21,038 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, b1d805c1-3ada-4888-9dbf-aae69d238b93, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:21,039 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, b1d805c1-3ada-4888-9dbf-aae69d238b93, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:21,040 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, b1d805c1-3ada-4888-9dbf-aae69d238b93, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:21,852 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:21,852 - INFO - __tests__.alter_compression.1714338  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-11-21 12:13:21,853 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:21,854 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 315a8fb4-1587-4130-8ed9-f30b2c1de3ba, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:21,855 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 315a8fb4-1587-4130-8ed9-f30b2c1de3ba, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:21,857 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 315a8fb4-1587-4130-8ed9-f30b2c1de3ba, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:25,208 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:25,209 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:25,209 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, ba33b01a-e32f-43bc-a937-067c2afc255d, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:25,210 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, ba33b01a-e32f-43bc-a937-067c2afc255d, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:25,216 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, ba33b01a-e32f-43bc-a937-067c2afc255d, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:25,709 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:25,709 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:25,710 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, fd1d8967-da4d-4a8f-ab89-4360bcde34ca, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:25,711 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, fd1d8967-da4d-4a8f-ab89-4360bcde34ca, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:25,712 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, fd1d8967-da4d-4a8f-ab89-4360bcde34ca, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:26,868 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:26,869 - INFO - __tests__.alter_compression.1714338  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-11-21 12:13:26,869 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:26,870 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, e1fd8b43-fe10-45a0-90a5-7c5a4fe55d68, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:26,870 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, e1fd8b43-fe10-45a0-90a5-7c5a4fe55d68, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:26,872 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, e1fd8b43-fe10-45a0-90a5-7c5a4fe55d68, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:30,248 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:30,249 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:30,250 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 0878ae87-95fd-4233-b6ce-1615beb1691a, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:30,251 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 0878ae87-95fd-4233-b6ce-1615beb1691a, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:30,253 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 0878ae87-95fd-4233-b6ce-1615beb1691a, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:30,600 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:30,601 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:30,602 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, af5298a0-975e-4521-9a08-d969cdc75385, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:30,603 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, af5298a0-975e-4521-9a08-d969cdc75385, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:30,608 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, af5298a0-975e-4521-9a08-d969cdc75385, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:31,165 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:31,165 - INFO - __tests__.alter_compression.1714338  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-11-21 12:13:31,166 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:31,167 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 4a5a650a-ca5c-4db9-a9f1-077b3062394f, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:31,167 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 4a5a650a-ca5c-4db9-a9f1-077b3062394f, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:31,169 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 4a5a650a-ca5c-4db9-a9f1-077b3062394f, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:34,483 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:34,484 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:34,485 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, a9c1b106-a548-4e08-bb6a-784deb481eb3, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:34,486 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, a9c1b106-a548-4e08-bb6a-784deb481eb3, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:34,489 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, a9c1b106-a548-4e08-bb6a-784deb481eb3, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:34,792 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:34,793 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:34,793 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 6c325faf-636d-4710-8a58-aadb311c16e6, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:34,794 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 6c325faf-636d-4710-8a58-aadb311c16e6, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:34,796 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 6c325faf-636d-4710-8a58-aadb311c16e6, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:35,340 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:35,341 - INFO - __tests__.alter_compression.1714338  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-11-21 12:13:35,342 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:35,342 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, a396c7bc-de38-4d81-a5e3-03bede3983ac, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:35,343 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, a396c7bc-de38-4d81-a5e3-03bede3983ac, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:35,345 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, a396c7bc-de38-4d81-a5e3-03bede3983ac, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:38,535 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:38,536 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:38,536 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, e720df66-da42-4e59-8541-65864bf0f0d5, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:38,537 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, e720df66-da42-4e59-8541-65864bf0f0d5, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:38,538 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, e720df66-da42-4e59-8541-65864bf0f0d5, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:38,801 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:38,801 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:38,802 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, b6c7d2da-1564-4dc5-b204-2d3b9f03ad0f, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:38,803 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, b6c7d2da-1564-4dc5-b204-2d3b9f03ad0f, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:38,804 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, b6c7d2da-1564-4dc5-b204-2d3b9f03ad0f, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:39,564 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:39,565 - INFO - __tests__.alter_compression.1714338  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-11-21 12:13:39,566 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:39,566 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, e58b37c2-3d68-4b6c-8534-6ab66b0bb2fe, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:39,567 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, e58b37c2-3d68-4b6c-8534-6ab66b0bb2fe, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:39,569 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, e58b37c2-3d68-4b6c-8534-6ab66b0bb2fe, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:42,720 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:42,720 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:42,721 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 3e3dca81-f11e-44b4-a830-86ed3c9583db, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:42,722 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 3e3dca81-f11e-44b4-a830-86ed3c9583db, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:42,724 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 3e3dca81-f11e-44b4-a830-86ed3c9583db, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:43,060 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:43,060 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:43,061 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, af984afe-e20c-49bc-868c-a83c589c4026, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:43,062 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, af984afe-e20c-49bc-868c-a83c589c4026, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:43,063 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, af984afe-e20c-49bc-868c-a83c589c4026, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:43,768 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:43,769 - INFO - __tests__.alter_compression.1714338  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-11-21 12:13:43,769 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:43,770 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 2afae0ac-ecd9-4003-a34f-82db7abd0621, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:43,771 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 2afae0ac-ecd9-4003-a34f-82db7abd0621, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:43,773 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 2afae0ac-ecd9-4003-a34f-82db7abd0621, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:47,152 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:47,153 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:47,154 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 5d994c11-8ffe-4958-b699-10154789df52, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:47,155 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 5d994c11-8ffe-4958-b699-10154789df52, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:47,156 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 5d994c11-8ffe-4958-b699-10154789df52, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:47,552 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:47,553 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:47,554 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 0677d6a1-e7ba-4f51-8f45-a40f8273b4f2, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:47,554 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 0677d6a1-e7ba-4f51-8f45-a40f8273b4f2, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:47,556 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 0677d6a1-e7ba-4f51-8f45-a40f8273b4f2, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:48,399 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:48,399 - INFO - __tests__.alter_compression.1714338  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-11-21 12:13:48,400 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:48,401 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, fe8f7569-fb95-40de-a4a1-e548e15059c6, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:48,402 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, fe8f7569-fb95-40de-a4a1-e548e15059c6, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:48,403 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, fe8f7569-fb95-40de-a4a1-e548e15059c6, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:49,517 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:49,517 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:49,518 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 48a48939-1592-4ce2-8321-eee2b6e895fa, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:49,519 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 48a48939-1592-4ce2-8321-eee2b6e895fa, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:49,520 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 48a48939-1592-4ce2-8321-eee2b6e895fa, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:49,795 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:49,796 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:49,796 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 2a1f034f-22ba-4ce3-9476-f61f01917477, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:49,797 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 2a1f034f-22ba-4ce3-9476-f61f01917477, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:49,798 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 2a1f034f-22ba-4ce3-9476-f61f01917477, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:50,050 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:50,051 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:13:50,052 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 3c3db9b1-95c3-40a1-abc9-7094f171b87a, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:13:50,053 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 3c3db9b1-95c3-40a1-abc9-7094f171b87a, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:13:50,054 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 3c3db9b1-95c3-40a1-abc9-7094f171b87a, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:13:53,596 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:03,691 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:03,692 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 7199bc21-d22d-4449-a666-7bd43fcb1287, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:14:03,693 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 7199bc21-d22d-4449-a666-7bd43fcb1287, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:03,695 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 7199bc21-d22d-4449-a666-7bd43fcb1287, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:06,269 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:06,290 - INFO - root.1714338  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1703584)
2025-11-21 12:14:06,291 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:06,292 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 4250bacf-16ae-4e2f-a971-6640551f7080, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:14:06,293 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 4250bacf-16ae-4e2f-a971-6640551f7080, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:06,296 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 4250bacf-16ae-4e2f-a971-6640551f7080, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:06,712 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:06,713 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:06,714 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 6fc63101-5235-466d-8a2a-91b7e7eed895, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:14:06,715 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 6fc63101-5235-466d-8a2a-91b7e7eed895, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:06,716 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 6fc63101-5235-466d-8a2a-91b7e7eed895, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:08,741 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:18,763 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:18,764 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 848e4f56-c96e-4aea-b0e3-f6258e7c652c, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:14:18,765 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 848e4f56-c96e-4aea-b0e3-f6258e7c652c, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:18,767 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 848e4f56-c96e-4aea-b0e3-f6258e7c652c, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:19,460 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:29,467 - DEBUG - ydb.query.pool.1714338  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:29,468 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(ExecuteQuery, 250123a2-877b-4545-8402-e4a3b18a6f1c, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:14:29,469 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(ExecuteQuery, 250123a2-877b-4545-8402-e4a3b18a6f1c, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:14:29,471 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(ExecuteQuery, 250123a2-877b-4545-8402-e4a3b18a6f1c, ghrun-ts5cnd6jre.auto.internal:13711): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:14:30,092 - DEBUG - ydb.query.pool.1714338  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=
2025-11-21 12:14:30,096 - INFO - root.1714338  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1870856)
2025-11-21 12:14:30,097 - INFO - root.1714338  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_14_compression` 8092160 / 1870856: 4.325378329491954
2025-11-21 12:14:30,099 - INFO - ya.test.1714338  - pytest_runtest_teardown: Test teardown
2025-11-21 12:14:30,100 - DEBUG - ydb.connection.1714338  - _prepare_call: RpcState(DeleteSession, 59020342-98e4-46f5-96ec-d41156a026e1, ghrun-ts5cnd6jre.auto.internal:13711): creating call state
2025-11-21 12:14:30,100 - DEBUG - ydb.connection.1714338  - _log_request: RpcState(DeleteSession, 59020342-98e4-46f5-96ec-d41156a026e1, ghrun-ts5cnd6jre.auto.internal:13711): request = { session_id: "ydb://session/3?node_id=1&id=OTkwMDEwZjItNDFkMzk1MjAtMTI3Njc3OWMtYjMyNDQxZjA=" }
2025-11-21 12:14:30,104 - DEBUG - ydb.connection.1714338  - _log_response: RpcState(DeleteSession, 59020342-98e4-46f5-96ec-d41156a026e1, ghrun-ts5cnd6jre.auto.internal:13711): response = { status: SUCCESS }
2025-11-21 12:14:30,106 - DEBUG - ydb.query.pool.1714338  - stop: All session were deleted.
2025-11-21 12:14:30,106 - DEBUG - ydb.topic.1714338  - close: Close topic client
2025-11-21 12:14:30,106 - DEBUG - ydb.topic.1714338  - close: Topic client was closed
2025-11-21 12:14:30,107 - DEBUG - ydb.connection.1714338  - close: Closing channel for endpoint localhost:13711
2025-11-21 12:14:30,111 - DEBUG - ydb.connection.1714338  - close: Closing channel for endpoint ghrun-ts5cnd6jre.auto.internal:13711
2025-11-21 12:14:30,119 - DEBUG - ydb.connection.1714338  - _rpc_error_handler: RpcState(AttachSession, 9fd11eea-54da-4e3b-878d-623d87b41dab, ghrun-ts5cnd6jre.auto.internal:13711): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-21 12:14:30,127 - INFO - ydb.pool.Discovery.1714338  - run: Successfully terminated discovery process
2025-11-21 12:14:52,137 - INFO - ydb.tests.library.harness.kikimr_runner.1714338  - stop: Stopped node localhost:13711/1
2025-11-21 12:14:52,139 - DEBUG - library.python.filelock.1714338  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3892
2025-11-21 12:14:52,139 - DEBUG - library.python.filelock.1714338  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21753
2025-11-21 12:14:52,140 - DEBUG - library.python.filelock.1714338  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7587
2025-11-21 12:14:52,140 - DEBUG - library.python.filelock.1714338  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13711
2025-11-21 12:14:52,140 - DEBUG - library.python.filelock.1714338  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7317
