2025-10-15 15:15:25,025 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:15:25,026 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19]
2025-10-15 15:15:25,026 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:15:25,027 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:15:25,029 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_19_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 19])
2025-10-15 15:15:25,029 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:25,030 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ee4ebd6c-be8f-48e6-aa94-95f517d441b6, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:25,031 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ee4ebd6c-be8f-48e6-aa94-95f517d441b6, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_19_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 19)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:25,049 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ee4ebd6c-be8f-48e6-aa94-95f517d441b6, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:28,767 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:28,767 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_19_compression created
2025-10-15 15:15:28,768 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:28,769 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 27b4ffb8-b2d3-4830-915b-a8fff0392d45, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:28,770 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 27b4ffb8-b2d3-4830-915b-a8fff0392d45, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:28,772 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 27b4ffb8-b2d3-4830-915b-a8fff0392d45, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:29,693 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:29,694 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:29,695 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4efaca4f-5504-4fe2-b8a5-5674ffc8dd95, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:29,695 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4efaca4f-5504-4fe2-b8a5-5674ffc8dd95, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:29,701 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4efaca4f-5504-4fe2-b8a5-5674ffc8dd95, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:35,654 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:35,655 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:35,655 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e796945d-d77a-4b01-ae40-5352a25e4593, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:35,656 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e796945d-d77a-4b01-ae40-5352a25e4593, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:35,658 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e796945d-d77a-4b01-ae40-5352a25e4593, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:37,014 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:37,015 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:37,016 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb3184b6-5f08-4580-a5b5-4bcb679c263b, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:37,017 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb3184b6-5f08-4580-a5b5-4bcb679c263b, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:37,019 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb3184b6-5f08-4580-a5b5-4bcb679c263b, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:38,813 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:38,814 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-10-15 15:15:38,815 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:38,815 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c33f9822-6d78-472c-bf7a-bbf3f73088a7, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:38,816 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c33f9822-6d78-472c-bf7a-bbf3f73088a7, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:38,818 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c33f9822-6d78-472c-bf7a-bbf3f73088a7, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:44,029 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:44,030 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:44,031 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 542a7b13-137a-4be7-96ee-bd950c94c284, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:44,032 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 542a7b13-137a-4be7-96ee-bd950c94c284, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:44,034 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 542a7b13-137a-4be7-96ee-bd950c94c284, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:44,859 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:44,860 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:44,861 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ee7c9e7a-75bd-44c2-a485-3f48798d97a9, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:44,862 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ee7c9e7a-75bd-44c2-a485-3f48798d97a9, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:44,864 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ee7c9e7a-75bd-44c2-a485-3f48798d97a9, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:45,400 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:45,401 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-10-15 15:15:45,401 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:45,402 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b30a8a5-27f1-4313-bb5b-72cddecca3a1, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:45,403 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b30a8a5-27f1-4313-bb5b-72cddecca3a1, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:45,410 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b30a8a5-27f1-4313-bb5b-72cddecca3a1, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:48,253 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:15:48,253 - 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-10-15 15:15:48,267 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:15:48,268 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 895ff7dc-c394-460d-a37f-5f256aa10c7d, localhost:1039): creating call state
2025-10-15 15:15:48,268 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 895ff7dc-c394-460d-a37f-5f256aa10c7d, localhost:1039): request = { database: "/Root" }
2025-10-15 15:15:48,277 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 895ff7dc-c394-460d-a37f-5f256aa10c7d, localhost:1039): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 1039 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-10-15 15:15:48,278 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:1039, location 1, ssl: False>]>
2025-10-15 15:15:48,279 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1039
2025-10-15 15:15:50,074 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:50,075 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:50,076 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5f2f315-67f4-4bb1-92c1-2268de7793ab, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:50,077 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5f2f315-67f4-4bb1-92c1-2268de7793ab, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:50,084 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5f2f315-67f4-4bb1-92c1-2268de7793ab, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:50,420 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:50,421 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:50,422 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 297b24b1-4549-4bfd-bae4-433771d7eed2, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:50,423 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 297b24b1-4549-4bfd-bae4-433771d7eed2, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:50,425 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 297b24b1-4549-4bfd-bae4-433771d7eed2, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:51,074 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:51,075 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-10-15 15:15:51,075 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:51,076 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 02e6ef1f-5e2a-4f6d-be17-06e48aa61778, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:51,077 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 02e6ef1f-5e2a-4f6d-be17-06e48aa61778, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:51,079 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 02e6ef1f-5e2a-4f6d-be17-06e48aa61778, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:55,858 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:55,859 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:55,860 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d987a47-1b1d-401a-bba1-94f3e250025d, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:55,861 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d987a47-1b1d-401a-bba1-94f3e250025d, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:55,863 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d987a47-1b1d-401a-bba1-94f3e250025d, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:56,198 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:56,199 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:56,200 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 76b6dc2b-446c-44f7-8346-d052f17bdaa8, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:56,204 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 76b6dc2b-446c-44f7-8346-d052f17bdaa8, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:56,206 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 76b6dc2b-446c-44f7-8346-d052f17bdaa8, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:56,781 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:56,782 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-10-15 15:15:56,783 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:56,784 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fce91cd7-a01c-447c-9620-dbd4991f733f, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:56,785 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fce91cd7-a01c-447c-9620-dbd4991f733f, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:56,787 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fce91cd7-a01c-447c-9620-dbd4991f733f, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:02,311 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:02,312 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:02,312 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b7621876-b548-4482-bc51-fe756c6f3d01, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:02,313 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b7621876-b548-4482-bc51-fe756c6f3d01, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:02,315 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b7621876-b548-4482-bc51-fe756c6f3d01, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:02,737 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:02,738 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:02,739 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cad68fdf-73a2-4f98-b918-4dd80b716cb5, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:02,740 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cad68fdf-73a2-4f98-b918-4dd80b716cb5, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:02,741 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cad68fdf-73a2-4f98-b918-4dd80b716cb5, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:03,536 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:03,537 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-10-15 15:16:03,537 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:03,538 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f92e1e23-3e57-4d2c-a8cd-7ba7883156c1, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:03,539 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f92e1e23-3e57-4d2c-a8cd-7ba7883156c1, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:03,541 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f92e1e23-3e57-4d2c-a8cd-7ba7883156c1, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:08,241 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:08,242 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:08,243 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 598d868c-e187-4c94-8e4f-62906a73aa49, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:08,244 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 598d868c-e187-4c94-8e4f-62906a73aa49, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:08,250 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 598d868c-e187-4c94-8e4f-62906a73aa49, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:08,610 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:08,611 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:08,612 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1e5e5dac-cbf6-4170-bb9d-265d2f31498c, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:08,613 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1e5e5dac-cbf6-4170-bb9d-265d2f31498c, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:08,614 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1e5e5dac-cbf6-4170-bb9d-265d2f31498c, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:09,232 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:09,233 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 606228, 'Portions': 340}}, blobs: {'__DEFAULT': {'Portions': 335, 'BlobSize': 1945584, 'BlobCount': 710}}
2025-10-15 15:16:09,234 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:09,234 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8f801b1c-caaa-4d2a-b1ff-1d6c10f500cb, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:09,235 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8f801b1c-caaa-4d2a-b1ff-1d6c10f500cb, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:09,264 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8f801b1c-caaa-4d2a-b1ff-1d6c10f500cb, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:13,701 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:13,702 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:13,703 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dc8ef8b5-ad32-4972-851c-c9c215a2cd12, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:13,703 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dc8ef8b5-ad32-4972-851c-c9c215a2cd12, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:13,704 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dc8ef8b5-ad32-4972-851c-c9c215a2cd12, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:14,134 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:14,135 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:14,136 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d9c5f65-72ed-4093-bdea-f388945d026f, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:14,137 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d9c5f65-72ed-4093-bdea-f388945d026f, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:14,138 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d9c5f65-72ed-4093-bdea-f388945d026f, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:14,760 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:14,761 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 321}}, blobs: {'__DEFAULT': {'Portions': 321, 'BlobSize': 2210624, 'BlobCount': 718}}
2025-10-15 15:16:14,761 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:14,762 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 291c6f44-735a-4dd2-b978-8c7fe2b2b623, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:14,763 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 291c6f44-735a-4dd2-b978-8c7fe2b2b623, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:14,765 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 291c6f44-735a-4dd2-b978-8c7fe2b2b623, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:18,728 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:18,728 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:18,729 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 68b18515-3343-4b53-bcc8-0a308c9285b6, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:18,730 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 68b18515-3343-4b53-bcc8-0a308c9285b6, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:18,731 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 68b18515-3343-4b53-bcc8-0a308c9285b6, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:18,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:18,955 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:18,956 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 50f90ead-7591-4a04-a151-dbeef6128265, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:18,957 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 50f90ead-7591-4a04-a151-dbeef6128265, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:18,958 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 50f90ead-7591-4a04-a151-dbeef6128265, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:19,419 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:19,419 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 316}}, blobs: {'__DEFAULT': {'Portions': 316, 'BlobSize': 2513136, 'BlobCount': 732}}
2025-10-15 15:16:19,420 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:19,420 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5f7c8492-dbd8-47e7-94f7-a879bf897a9b, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:19,421 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5f7c8492-dbd8-47e7-94f7-a879bf897a9b, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:19,423 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5f7c8492-dbd8-47e7-94f7-a879bf897a9b, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:23,583 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:23,584 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:23,585 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f8faf4a-fac0-42fc-9909-f3a1d66a1f29, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:23,586 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f8faf4a-fac0-42fc-9909-f3a1d66a1f29, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:23,587 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f8faf4a-fac0-42fc-9909-f3a1d66a1f29, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:23,803 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:23,804 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:23,804 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f524372-8878-4364-b697-510e75dc20f5, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:23,805 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f524372-8878-4364-b697-510e75dc20f5, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:23,807 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f524372-8878-4364-b697-510e75dc20f5, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:24,189 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:24,190 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 912645, 'Portions': 360}}, blobs: {'__DEFAULT': {'Portions': 352, 'BlobSize': 2824800, 'BlobCount': 812}}
2025-10-15 15:16:24,191 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:24,192 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3453e913-6464-4ab1-95a3-2d0b7fb6430c, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:24,192 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3453e913-6464-4ab1-95a3-2d0b7fb6430c, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_19_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:24,198 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3453e913-6464-4ab1-95a3-2d0b7fb6430c, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:28,013 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:28,014 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:28,015 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96182731-f7d3-4475-a11c-f2e5946714a9, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:28,015 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96182731-f7d3-4475-a11c-f2e5946714a9, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:28,016 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96182731-f7d3-4475-a11c-f2e5946714a9, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:28,270 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:28,271 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:28,271 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3ac77422-218c-46b2-b9f2-55144e7894d2, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:28,272 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3ac77422-218c-46b2-b9f2-55144e7894d2, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:28,274 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3ac77422-218c-46b2-b9f2-55144e7894d2, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:28,686 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:28,687 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_19_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 376}}, blobs: {'__DEFAULT': {'Portions': 376, 'BlobSize': 3133520, 'BlobCount': 870}}
2025-10-15 15:16:28,688 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:28,688 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 82207037-d170-4bd0-b9a6-afb1b3cac3e9, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:28,689 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 82207037-d170-4bd0-b9a6-afb1b3cac3e9, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_19_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:28,691 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 82207037-d170-4bd0-b9a6-afb1b3cac3e9, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:30,026 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:30,027 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:30,028 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b3efb97-f0d1-42ac-8064-9945b5ac19ae, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:30,029 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b3efb97-f0d1-42ac-8064-9945b5ac19ae, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:30,041 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b3efb97-f0d1-42ac-8064-9945b5ac19ae, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:30,410 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:30,411 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:30,412 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 110dc0ab-a8c5-49e2-a449-ee696266b434, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:30,412 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 110dc0ab-a8c5-49e2-a449-ee696266b434, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:30,414 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 110dc0ab-a8c5-49e2-a449-ee696266b434, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:30,757 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:30,758 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:30,759 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f6dc34a9-6393-4489-8efc-347888df11e6, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:30,760 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f6dc34a9-6393-4489-8efc-347888df11e6, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:30,765 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f6dc34a9-6393-4489-8efc-347888df11e6, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:33,152 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:43,172 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:43,173 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9112ac88-b3aa-4cd9-9e30-3c613889389b, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:43,174 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9112ac88-b3aa-4cd9-9e30-3c613889389b, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:43,176 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9112ac88-b3aa-4cd9-9e30-3c613889389b, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:44,857 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:44,901 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1526328)
2025-10-15 15:16:44,902 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:44,902 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2a266319-8728-486c-a8f7-c480de82d8fe, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:44,903 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2a266319-8728-486c-a8f7-c480de82d8fe, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:44,909 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2a266319-8728-486c-a8f7-c480de82d8fe, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:45,161 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:45,161 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:45,162 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, faa52991-8f24-4b58-b86d-b650705c9b17, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:45,163 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, faa52991-8f24-4b58-b86d-b650705c9b17, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:45,165 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, faa52991-8f24-4b58-b86d-b650705c9b17, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:46,938 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:56,960 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:56,962 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 248efe90-aec3-461a-bbf2-6f6180872923, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:56,963 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 248efe90-aec3-461a-bbf2-6f6180872923, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_19_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:56,964 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 248efe90-aec3-461a-bbf2-6f6180872923, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:58,614 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:16:58,637 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_19_compression`, volumes `value` (8000000, 1526328)
2025-10-15 15:16:58,638 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_19_compression` 8075888 / 1526328: 5.29105670602911
2025-10-15 15:16:58,641 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-15 15:16:58,642 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 6a44fd44-0cc1-4f29-83a2-b3441e7ca8b1, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:16:58,643 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 6a44fd44-0cc1-4f29-83a2-b3441e7ca8b1, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" }
2025-10-15 15:16:58,647 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 6a44fd44-0cc1-4f29-83a2-b3441e7ca8b1, ghrun-lvxbqnxafi.auto.internal:1039): response = { status: SUCCESS }
2025-10-15 15:16:58,648 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-15 15:16:58,649 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, b68ec8c8-151b-430e-830a-81e3f2e3e887, ghrun-lvxbqnxafi.auto.internal:1039): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-10-15 15:16:58,650 - DEBUG - ydb.topic - close: Close topic client
2025-10-15 15:16:58,652 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-15 15:16:58,652 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1039
2025-10-15 15:16:58,653 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-lvxbqnxafi.auto.internal:1039
2025-10-15 15:16:58,656 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-15 15:17:10,662 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:1039/1
2025-10-15 15:17:10,663 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11333
2025-10-15 15:17:10,664 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19817
2025-10-15 15:17:10,665 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26984
2025-10-15 15:17:10,665 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1039
2025-10-15 15:17:10,665 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25658
