2025-11-06 10:18:30,770 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-06 10:18:30,771 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14]
2025-11-06 10:18:30,771 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-06 10:18:30,771 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-06 10:18:30,773 - INFO - ya.test - 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-06 10:18:30,774 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:30,775 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e25eb377-e4dd-4d65-86c2-bcedb1c7ec25, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:30,776 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e25eb377-e4dd-4d65-86c2-bcedb1c7ec25, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:30,777 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e25eb377-e4dd-4d65-86c2-bcedb1c7ec25, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:33,772 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:33,772 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_14_compression created
2025-11-06 10:18:33,773 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:33,773 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 47465e65-e930-4772-a006-1c0745696c8e, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:33,774 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 47465e65-e930-4772-a006-1c0745696c8e, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:33,775 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 47465e65-e930-4772-a006-1c0745696c8e, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:34,882 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:34,883 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:34,884 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 89c0deea-d705-4ec5-a89f-970efdffc2ba, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:34,885 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 89c0deea-d705-4ec5-a89f-970efdffc2ba, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:34,886 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 89c0deea-d705-4ec5-a89f-970efdffc2ba, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:38,963 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:38,963 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:38,964 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93305bc2-40ea-4435-8236-ab8c16bb81b8, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:38,965 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93305bc2-40ea-4435-8236-ab8c16bb81b8, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:38,966 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93305bc2-40ea-4435-8236-ab8c16bb81b8, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:40,151 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:40,152 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:40,153 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7b438596-946a-468f-93f5-0e22a322b1c2, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:40,154 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7b438596-946a-468f-93f5-0e22a322b1c2, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:40,155 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7b438596-946a-468f-93f5-0e22a322b1c2, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:42,370 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:42,371 - INFO - __tests__.alter_compression - 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-06 10:18:42,371 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:42,372 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93ab1167-e7f4-4c7b-b722-c1745a6c799e, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:42,373 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93ab1167-e7f4-4c7b-b722-c1745a6c799e, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:42,374 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93ab1167-e7f4-4c7b-b722-c1745a6c799e, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:45,460 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:45,460 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:45,461 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd9cd6da-b2f2-475d-bb4d-15f177bdc53b, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:45,462 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd9cd6da-b2f2-475d-bb4d-15f177bdc53b, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:45,463 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd9cd6da-b2f2-475d-bb4d-15f177bdc53b, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:45,804 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:45,805 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:45,806 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a15fe28c-eb81-426c-8744-2dc101374194, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:45,807 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a15fe28c-eb81-426c-8744-2dc101374194, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:45,809 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a15fe28c-eb81-426c-8744-2dc101374194, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:46,225 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:46,226 - INFO - __tests__.alter_compression - 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-06 10:18:46,226 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:46,227 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d71f4745-cd19-4f42-82f2-93acb107674a, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:46,227 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d71f4745-cd19-4f42-82f2-93acb107674a, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:46,229 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d71f4745-cd19-4f42-82f2-93acb107674a, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:48,905 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:18:48,906 - DEBUG - ydb.connection - 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-06 10:18:48,917 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-06 10:18:48,917 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 3f219eb0-68f8-48cd-b071-8aff1ce0201c, localhost:4017): creating call state
2025-11-06 10:18:48,918 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 3f219eb0-68f8-48cd-b071-8aff1ce0201c, localhost:4017): request = { database: "/Root" }
2025-11-06 10:18:48,954 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 3f219eb0-68f8-48cd-b071-8aff1ce0201c, localhost:4017): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ew6xquenpy.auto.internal" port: 4017 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-06 10:18:48,955 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ew6xquenpy.auto.internal:4017, location 1, ssl: False>]>
2025-11-06 10:18:48,955 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:4017
2025-11-06 10:18:48,972 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:48,972 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:48,973 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 446e94ce-ef28-4b94-bf60-ebaad16ae154, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:48,974 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 446e94ce-ef28-4b94-bf60-ebaad16ae154, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:48,978 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 446e94ce-ef28-4b94-bf60-ebaad16ae154, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:49,421 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:49,422 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:49,422 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5bacde3f-9489-4b9e-ba48-7dd01bccf1b4, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:49,423 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5bacde3f-9489-4b9e-ba48-7dd01bccf1b4, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:49,424 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5bacde3f-9489-4b9e-ba48-7dd01bccf1b4, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:49,913 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:49,914 - INFO - __tests__.alter_compression - 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-06 10:18:49,914 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:49,915 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7a35f0d8-4843-4d3f-89b6-559a2badc517, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:49,915 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7a35f0d8-4843-4d3f-89b6-559a2badc517, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:49,916 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7a35f0d8-4843-4d3f-89b6-559a2badc517, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:52,551 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:52,551 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:52,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a8436fc1-fe30-4c02-ac80-2582a3d6ffc4, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:52,553 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a8436fc1-fe30-4c02-ac80-2582a3d6ffc4, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:52,554 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a8436fc1-fe30-4c02-ac80-2582a3d6ffc4, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:52,976 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:52,977 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:52,978 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 02b6f7f9-6794-4a1f-befc-6a0507bedf86, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:52,978 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 02b6f7f9-6794-4a1f-befc-6a0507bedf86, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:52,980 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 02b6f7f9-6794-4a1f-befc-6a0507bedf86, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:53,525 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:53,526 - INFO - __tests__.alter_compression - 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-06 10:18:53,527 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:53,527 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, faa743f1-29fa-468d-b5b0-6ba3cc5f5a1a, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:53,528 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, faa743f1-29fa-468d-b5b0-6ba3cc5f5a1a, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:53,529 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, faa743f1-29fa-468d-b5b0-6ba3cc5f5a1a, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:56,592 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:56,593 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:56,594 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 11766e5b-eabd-40d7-b1e6-6aa7f165a253, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:56,595 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 11766e5b-eabd-40d7-b1e6-6aa7f165a253, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:56,596 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 11766e5b-eabd-40d7-b1e6-6aa7f165a253, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:57,007 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:57,008 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:57,009 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f34b464c-014e-4afd-bc65-2b1f29969121, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:57,010 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f34b464c-014e-4afd-bc65-2b1f29969121, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:57,041 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f34b464c-014e-4afd-bc65-2b1f29969121, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:18:57,675 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:57,676 - INFO - __tests__.alter_compression - 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-06 10:18:57,676 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:18:57,677 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e5c3585-0358-4022-9eb3-9581c5eb68e4, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:18:57,677 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e5c3585-0358-4022-9eb3-9581c5eb68e4, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:18:57,678 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e5c3585-0358-4022-9eb3-9581c5eb68e4, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:00,775 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:00,776 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:00,776 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 20543ee1-898b-485f-9910-49012df9492e, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:00,777 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 20543ee1-898b-485f-9910-49012df9492e, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:00,778 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 20543ee1-898b-485f-9910-49012df9492e, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:01,086 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:01,087 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:01,088 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 156ff793-db42-4c41-b94f-54f9ddb8871f, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:01,089 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 156ff793-db42-4c41-b94f-54f9ddb8871f, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:01,105 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 156ff793-db42-4c41-b94f-54f9ddb8871f, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:01,711 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:01,712 - INFO - __tests__.alter_compression - 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-06 10:19:01,712 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:01,713 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4956c95d-a57c-4435-bf5a-d27cadfb94b8, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:01,714 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4956c95d-a57c-4435-bf5a-d27cadfb94b8, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:01,715 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4956c95d-a57c-4435-bf5a-d27cadfb94b8, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:05,044 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:05,045 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:05,046 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3f005391-7bbf-4965-bfb4-63ef1750e360, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:05,046 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3f005391-7bbf-4965-bfb4-63ef1750e360, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:05,055 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3f005391-7bbf-4965-bfb4-63ef1750e360, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:05,480 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:05,481 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:05,482 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92d8085e-c146-485d-a002-321b1d5d0a59, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:05,484 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92d8085e-c146-485d-a002-321b1d5d0a59, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:05,486 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92d8085e-c146-485d-a002-321b1d5d0a59, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:06,230 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:06,230 - INFO - __tests__.alter_compression - 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-06 10:19:06,231 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:06,232 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2157cf77-02de-4b60-b032-bbb207c936bd, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:06,233 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2157cf77-02de-4b60-b032-bbb207c936bd, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:06,234 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2157cf77-02de-4b60-b032-bbb207c936bd, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:08,948 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:08,949 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:08,949 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 78f373b5-6f07-4003-8fb8-9eabddd67287, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:08,951 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 78f373b5-6f07-4003-8fb8-9eabddd67287, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:08,952 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 78f373b5-6f07-4003-8fb8-9eabddd67287, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:09,232 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:09,233 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:09,234 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e69b72a9-01d7-44ed-8096-5346837e03f1, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:09,234 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e69b72a9-01d7-44ed-8096-5346837e03f1, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:09,236 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e69b72a9-01d7-44ed-8096-5346837e03f1, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:09,804 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:09,804 - INFO - __tests__.alter_compression - 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-06 10:19:09,805 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:09,806 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 53083296-8dda-4ba7-8db5-d8b1d97ddf29, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:09,807 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 53083296-8dda-4ba7-8db5-d8b1d97ddf29, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:09,808 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 53083296-8dda-4ba7-8db5-d8b1d97ddf29, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:12,470 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:12,471 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:12,472 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1a7a6fca-c7bd-4303-a6d5-14de18b5c157, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:12,472 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1a7a6fca-c7bd-4303-a6d5-14de18b5c157, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:12,474 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1a7a6fca-c7bd-4303-a6d5-14de18b5c157, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:12,784 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:12,785 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:12,785 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 53de4074-a245-42b1-b643-4513133f5881, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:12,787 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 53de4074-a245-42b1-b643-4513133f5881, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:12,788 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 53de4074-a245-42b1-b643-4513133f5881, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:13,385 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:13,386 - INFO - __tests__.alter_compression - 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-06 10:19:13,386 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:13,387 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f40fa917-af52-45bd-b541-cc0783d845d0, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:13,388 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f40fa917-af52-45bd-b541-cc0783d845d0, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:13,390 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f40fa917-af52-45bd-b541-cc0783d845d0, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:15,984 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:15,985 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:15,986 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2ebe2b5-36fe-464c-b7f0-ecd8ffc778fa, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:15,987 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2ebe2b5-36fe-464c-b7f0-ecd8ffc778fa, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:15,988 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2ebe2b5-36fe-464c-b7f0-ecd8ffc778fa, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:16,396 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:16,397 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:16,398 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a4153262-3ae5-4cfa-bc31-9554deff0d48, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:16,399 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a4153262-3ae5-4cfa-bc31-9554deff0d48, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:16,400 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a4153262-3ae5-4cfa-bc31-9554deff0d48, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:17,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:17,138 - INFO - __tests__.alter_compression - 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-06 10:19:17,139 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:17,139 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 64517f4f-eacc-468c-b7d5-a73f02089521, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:17,140 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 64517f4f-eacc-468c-b7d5-a73f02089521, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:17,142 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 64517f4f-eacc-468c-b7d5-a73f02089521, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:18,219 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:18,220 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:18,221 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 075dc601-4bde-4ecf-9f7b-686481b3e927, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:18,222 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 075dc601-4bde-4ecf-9f7b-686481b3e927, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:18,224 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 075dc601-4bde-4ecf-9f7b-686481b3e927, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:18,540 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:18,541 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:18,541 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d36618d9-286b-406d-9edd-425ad80e4664, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:18,542 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d36618d9-286b-406d-9edd-425ad80e4664, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:18,543 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d36618d9-286b-406d-9edd-425ad80e4664, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:18,864 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:18,865 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:18,865 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7c8d8b1d-2165-4655-be8d-39a3b99ae557, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:18,866 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7c8d8b1d-2165-4655-be8d-39a3b99ae557, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:18,868 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7c8d8b1d-2165-4655-be8d-39a3b99ae557, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:22,837 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:32,882 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:32,883 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d7f47c2-3257-4ad5-8fb3-c57a013ec82c, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:32,884 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d7f47c2-3257-4ad5-8fb3-c57a013ec82c, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:32,886 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d7f47c2-3257-4ad5-8fb3-c57a013ec82c, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:36,611 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:46,647 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:46,648 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 55b6cc97-6e70-42b2-ab0f-4b882fe09ef4, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:46,649 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 55b6cc97-6e70-42b2-ab0f-4b882fe09ef4, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:46,650 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 55b6cc97-6e70-42b2-ab0f-4b882fe09ef4, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:48,055 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:58,079 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:19:58,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e5e48ca-50a6-498a-958e-8411543892bb, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:19:58,081 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e5e48ca-50a6-498a-958e-8411543892bb, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:19:58,082 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e5e48ca-50a6-498a-958e-8411543892bb, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:19:58,823 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:20:08,831 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:20:08,832 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cf77f961-6a3d-4931-9398-124e83631cea, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:20:08,833 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cf77f961-6a3d-4931-9398-124e83631cea, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:20:08,834 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cf77f961-6a3d-4931-9398-124e83631cea, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:20:09,654 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:20:09,658 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1870856)
2025-11-06 10:20:09,659 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:20:09,660 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 25d67787-49d4-42f1-8126-205e26b432fc, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:20:09,660 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 25d67787-49d4-42f1-8126-205e26b432fc, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:20:09,661 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 25d67787-49d4-42f1-8126-205e26b432fc, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:20:10,012 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:20:10,012 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:20:10,013 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca284b88-e14b-483b-9b6a-afed45e7b566, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:20:10,014 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca284b88-e14b-483b-9b6a-afed45e7b566, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:20:10,015 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca284b88-e14b-483b-9b6a-afed45e7b566, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:20:10,627 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:20:20,635 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:20:20,636 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a3c7146d-4b9d-4205-be19-dca5db428fd1, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:20:20,636 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a3c7146d-4b9d-4205-be19-dca5db428fd1, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" 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-06 10:20:20,638 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a3c7146d-4b9d-4205-be19-dca5db428fd1, ghrun-ew6xquenpy.auto.internal:4017): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:20:21,475 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==
2025-11-06 10:20:21,478 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1870856)
2025-11-06 10:20:21,478 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_14_compression` 8092160 / 1870856: 4.325378329491954
2025-11-06 10:20:21,480 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-06 10:20:21,481 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 434c3edb-ee7c-42c2-a1e4-5d8c727b177e, ghrun-ew6xquenpy.auto.internal:4017): creating call state
2025-11-06 10:20:21,482 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 434c3edb-ee7c-42c2-a1e4-5d8c727b177e, ghrun-ew6xquenpy.auto.internal:4017): request = { session_id: "ydb://session/3?node_id=1&id=N2MzMjc0MTctOGJmZDg2MC1jZjBkYTQ5ZS1lZThhNmM1YQ==" }
2025-11-06 10:20:21,488 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 434c3edb-ee7c-42c2-a1e4-5d8c727b177e, ghrun-ew6xquenpy.auto.internal:4017): response = { status: SUCCESS }
2025-11-06 10:20:21,489 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-06 10:20:21,490 - DEBUG - ydb.topic - close: Close topic client
2025-11-06 10:20:21,490 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-06 10:20:21,491 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:4017
2025-11-06 10:20:21,491 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-ew6xquenpy.auto.internal:4017
2025-11-06 10:20:21,493 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-06 10:20:33,503 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:4017/1
2025-11-06 10:20:33,505 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15155
2025-11-06 10:20:33,505 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32682
2025-11-06 10:20:33,506 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17444
2025-11-06 10:20:33,512 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4017
2025-11-06 10:20:33,512 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11636
