2025-11-21 20:45:18,469 - INFO - ya.test.678902  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:45:18,469 - INFO - ya.test.678902  - pytest_runtest_setup: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16]
2025-11-21 20:45:18,469 - INFO - ya.test.678902  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:45:18,470 - INFO - ya.test.678902  - pytest_runtest_setup: Test setup
2025-11-21 20:45:18,472 - INFO - ya.test.678902  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16])
2025-11-21 20:45:18,473 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:18,474 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 40bc6bfb-2ec9-4713-9b62-4047c1a4f3f6, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:18,475 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 40bc6bfb-2ec9-4713-9b62-4047c1a4f3f6, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_16_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 16)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:18,476 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 40bc6bfb-2ec9-4713-9b62-4047c1a4f3f6, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:24,638 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:24,639 - INFO - __tests__.alter_compression.678902  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_16_compression created
2025-11-21 20:45:24,639 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:24,640 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 8c71f000-fb4b-47c4-b5f6-b0aaa8ad9cf9, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:24,640 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 8c71f000-fb4b-47c4-b5f6-b0aaa8ad9cf9, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:24,641 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 8c71f000-fb4b-47c4-b5f6-b0aaa8ad9cf9, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:26,633 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:26,633 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:26,634 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 259465ca-43cd-4979-84cb-32ce0af2a58c, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:26,635 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 259465ca-43cd-4979-84cb-32ce0af2a58c, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:26,641 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 259465ca-43cd-4979-84cb-32ce0af2a58c, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:34,381 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:34,381 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:34,382 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 074ec4dd-93cb-439f-b73b-b6966c0e808b, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:34,382 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 074ec4dd-93cb-439f-b73b-b6966c0e808b, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:34,384 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 074ec4dd-93cb-439f-b73b-b6966c0e808b, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:36,002 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:36,003 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:36,004 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, e63f0d0f-fded-41b8-86b9-2d8ebd739f4c, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:36,005 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, e63f0d0f-fded-41b8-86b9-2d8ebd739f4c, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:36,007 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, e63f0d0f-fded-41b8-86b9-2d8ebd739f4c, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:40,115 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:40,116 - INFO - __tests__.alter_compression.678902  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-21 20:45:40,116 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:40,117 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 44c5b052-f61a-4fa8-afa9-70441570629d, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:40,118 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 44c5b052-f61a-4fa8-afa9-70441570629d, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:40,120 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 44c5b052-f61a-4fa8-afa9-70441570629d, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:48,987 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:48,988 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:48,988 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 6f49c2c8-5bd6-4d40-a84c-7fb10e7cba25, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:48,989 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 6f49c2c8-5bd6-4d40-a84c-7fb10e7cba25, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:48,990 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 6f49c2c8-5bd6-4d40-a84c-7fb10e7cba25, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:49,640 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:49,641 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:49,641 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 2ea1456f-d4ba-4faa-9246-f66088770bc9, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:49,642 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 2ea1456f-d4ba-4faa-9246-f66088770bc9, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:49,653 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 2ea1456f-d4ba-4faa-9246-f66088770bc9, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:50,670 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:50,671 - INFO - __tests__.alter_compression.678902  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-21 20:45:50,671 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:50,672 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 287bbb3d-328c-4ebf-9d4b-8e9cf7b40dc7, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:50,673 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 287bbb3d-328c-4ebf-9d4b-8e9cf7b40dc7, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:50,696 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 287bbb3d-328c-4ebf-9d4b-8e9cf7b40dc7, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:57,815 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:57,815 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:57,816 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 72619b90-6672-49be-9d58-4c8a40195795, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:57,817 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 72619b90-6672-49be-9d58-4c8a40195795, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:57,818 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 72619b90-6672-49be-9d58-4c8a40195795, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:57,935 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.678902  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 20:45:57,935 - DEBUG - ydb.connection.678902  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 20:45:57,976 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.678902  - context_resolve: Resolving endpoints for database /Root
2025-11-21 20:45:57,977 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ListEndpoints, 5ddc2098-36f4-4829-8560-239a2057a504, localhost:14957): creating call state
2025-11-21 20:45:57,977 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ListEndpoints, 5ddc2098-36f4-4829-8560-239a2057a504, localhost:14957): request = { database: "/Root" }
2025-11-21 20:45:58,105 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ListEndpoints, 5ddc2098-36f4-4829-8560-239a2057a504, localhost:14957): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-frzglb4yoy.auto.internal" port: 14957 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-21 20:45:58,112 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.678902  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-frzglb4yoy.auto.internal:14957, location 1, ssl: False>]>
2025-11-21 20:45:58,113 - DEBUG - ydb.connection.678902  - close: Closing channel for endpoint localhost:14957
2025-11-21 20:45:58,611 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:58,612 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:45:58,613 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, b6d83734-78f4-4380-a5a1-b6d1bcfe9716, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:45:58,614 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, b6d83734-78f4-4380-a5a1-b6d1bcfe9716, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:58,624 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, b6d83734-78f4-4380-a5a1-b6d1bcfe9716, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:00,177 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:00,178 - INFO - __tests__.alter_compression.678902  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-21 20:46:00,178 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:00,179 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, fe3efe3c-2b86-4126-bbea-9e046d906840, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:00,180 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, fe3efe3c-2b86-4126-bbea-9e046d906840, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:00,191 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, fe3efe3c-2b86-4126-bbea-9e046d906840, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:09,433 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:09,434 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:09,434 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, fb431cac-77e8-48a4-aa89-b0cc12ac5419, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:09,435 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, fb431cac-77e8-48a4-aa89-b0cc12ac5419, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:09,437 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, fb431cac-77e8-48a4-aa89-b0cc12ac5419, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:10,149 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:10,150 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:10,151 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 2ac8cf40-9fd4-42d1-8fee-5631da2c2ce3, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:10,152 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 2ac8cf40-9fd4-42d1-8fee-5631da2c2ce3, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:10,156 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 2ac8cf40-9fd4-42d1-8fee-5631da2c2ce3, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:11,343 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:11,344 - INFO - __tests__.alter_compression.678902  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-21 20:46:11,344 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:11,345 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 58fde509-dd25-4d72-abaf-981148e95316, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:11,346 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 58fde509-dd25-4d72-abaf-981148e95316, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:11,350 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 58fde509-dd25-4d72-abaf-981148e95316, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:20,518 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:20,519 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:20,520 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 8b5b9e91-34ee-48f8-aaa2-def1bd874385, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:20,521 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 8b5b9e91-34ee-48f8-aaa2-def1bd874385, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:20,526 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 8b5b9e91-34ee-48f8-aaa2-def1bd874385, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:21,108 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:21,109 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:21,110 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 1bdb66bd-29ef-4c69-84e2-d2ff8b0b8a1e, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:21,111 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 1bdb66bd-29ef-4c69-84e2-d2ff8b0b8a1e, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:21,113 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 1bdb66bd-29ef-4c69-84e2-d2ff8b0b8a1e, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:22,199 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:22,200 - INFO - __tests__.alter_compression.678902  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-21 20:46:22,200 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:22,201 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, afdcca9d-0f1e-462e-bf68-d533b690348a, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:22,202 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, afdcca9d-0f1e-462e-bf68-d533b690348a, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:22,209 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, afdcca9d-0f1e-462e-bf68-d533b690348a, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:29,169 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:29,170 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:29,171 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, ef294d32-f9cc-47c7-a40f-184e0b9b7f41, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:29,172 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, ef294d32-f9cc-47c7-a40f-184e0b9b7f41, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:29,187 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, ef294d32-f9cc-47c7-a40f-184e0b9b7f41, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:30,114 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:30,115 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:30,116 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 734a876c-04aa-42e6-835d-4b3ed31ecc2f, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:30,117 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 734a876c-04aa-42e6-835d-4b3ed31ecc2f, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:30,123 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 734a876c-04aa-42e6-835d-4b3ed31ecc2f, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:31,071 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:31,072 - INFO - __tests__.alter_compression.678902  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-21 20:46:31,072 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:31,073 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, a51e03e8-38f9-4520-b9c4-6b308ed8b2e7, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:31,074 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, a51e03e8-38f9-4520-b9c4-6b308ed8b2e7, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:31,077 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, a51e03e8-38f9-4520-b9c4-6b308ed8b2e7, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:38,581 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:38,582 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:38,583 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 96bc9ba8-fdf5-4758-b5ca-7d4a134cafa6, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:38,584 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 96bc9ba8-fdf5-4758-b5ca-7d4a134cafa6, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:38,596 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 96bc9ba8-fdf5-4758-b5ca-7d4a134cafa6, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:39,483 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:39,485 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:39,485 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, c9e3f9aa-e3e0-47bd-814c-2ad04a78288d, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:39,488 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, c9e3f9aa-e3e0-47bd-814c-2ad04a78288d, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:39,489 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, c9e3f9aa-e3e0-47bd-814c-2ad04a78288d, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:40,320 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:40,321 - INFO - __tests__.alter_compression.678902  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-21 20:46:40,322 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:40,323 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 1c980a11-f14c-4177-9b62-4a2976ef98f0, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:40,324 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 1c980a11-f14c-4177-9b62-4a2976ef98f0, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:40,326 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 1c980a11-f14c-4177-9b62-4a2976ef98f0, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:46,140 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:46,141 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:46,142 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 57e5d787-b8b5-4ccc-9856-11a6bd08697b, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:46,143 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 57e5d787-b8b5-4ccc-9856-11a6bd08697b, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:46,144 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 57e5d787-b8b5-4ccc-9856-11a6bd08697b, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:46,909 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:46,909 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:46,910 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 80da8ed1-f703-4bad-a161-99fdeb077975, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:46,911 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 80da8ed1-f703-4bad-a161-99fdeb077975, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:46,914 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 80da8ed1-f703-4bad-a161-99fdeb077975, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:47,964 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:47,965 - INFO - __tests__.alter_compression.678902  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-21 20:46:47,966 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:47,966 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 99cd6f93-44bf-4e78-8e14-a22fcbcf4b6b, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:47,967 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 99cd6f93-44bf-4e78-8e14-a22fcbcf4b6b, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:47,971 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 99cd6f93-44bf-4e78-8e14-a22fcbcf4b6b, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:54,157 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:54,157 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:54,158 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 0f73ac51-bc0c-4692-bc02-bd85e77bfd10, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:54,159 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 0f73ac51-bc0c-4692-bc02-bd85e77bfd10, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:54,163 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 0f73ac51-bc0c-4692-bc02-bd85e77bfd10, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:54,805 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:54,806 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:54,807 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 51abbc13-423b-4022-9ef7-776950832f2b, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:54,808 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 51abbc13-423b-4022-9ef7-776950832f2b, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:54,811 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 51abbc13-423b-4022-9ef7-776950832f2b, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:56,183 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:56,184 - INFO - __tests__.alter_compression.678902  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-21 20:46:56,185 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:46:56,186 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 4fc5e471-99fd-4824-8097-31abc12ca414, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:46:56,187 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 4fc5e471-99fd-4824-8097-31abc12ca414, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:56,188 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 4fc5e471-99fd-4824-8097-31abc12ca414, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:03,099 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:03,100 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:03,100 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, a6c67f9a-5ce8-4f84-93bd-2f92421d4cb8, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:47:03,101 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, a6c67f9a-5ce8-4f84-93bd-2f92421d4cb8, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:03,108 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, a6c67f9a-5ce8-4f84-93bd-2f92421d4cb8, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:03,689 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:03,690 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:03,691 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 217cada4-b358-4218-8df0-8869a433f7b9, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:47:03,692 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 217cada4-b358-4218-8df0-8869a433f7b9, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:03,698 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 217cada4-b358-4218-8df0-8869a433f7b9, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:04,879 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:04,880 - INFO - __tests__.alter_compression.678902  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-21 20:47:04,881 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:04,882 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 751e2991-e746-4123-8310-dec76a8a5e15, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:47:04,883 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 751e2991-e746-4123-8310-dec76a8a5e15, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:04,885 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 751e2991-e746-4123-8310-dec76a8a5e15, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:07,621 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:07,622 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:07,623 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 4e719c82-adb1-4c42-83da-de1e07c92111, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:47:07,624 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 4e719c82-adb1-4c42-83da-de1e07c92111, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:07,626 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 4e719c82-adb1-4c42-83da-de1e07c92111, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:08,400 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:08,401 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:08,402 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 2fd512ce-298f-482c-ba7b-ef98037ce13a, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:47:08,403 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 2fd512ce-298f-482c-ba7b-ef98037ce13a, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:08,409 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 2fd512ce-298f-482c-ba7b-ef98037ce13a, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:08,971 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:08,972 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:08,973 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, da0cdb5c-b181-4fcd-8d45-363ff4b56eae, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:47:08,974 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, da0cdb5c-b181-4fcd-8d45-363ff4b56eae, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:08,976 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, da0cdb5c-b181-4fcd-8d45-363ff4b56eae, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:14,984 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:25,033 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:25,034 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 3048e0ba-a50c-489a-ad27-b33e67170450, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:47:25,035 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 3048e0ba-a50c-489a-ad27-b33e67170450, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:25,036 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 3048e0ba-a50c-489a-ad27-b33e67170450, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:30,680 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:40,517 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.678902  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 20:47:40,518 - DEBUG - ydb.connection.678902  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 20:47:40,551 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.678902  - context_resolve: Resolving endpoints for database /Root
2025-11-21 20:47:40,552 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ListEndpoints, 42821aa6-30aa-4e64-bacd-8c6283e499ce, localhost:14957): creating call state
2025-11-21 20:47:40,552 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ListEndpoints, 42821aa6-30aa-4e64-bacd-8c6283e499ce, localhost:14957): request = { database: "/Root" }
2025-11-21 20:47:40,561 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ListEndpoints, 42821aa6-30aa-4e64-bacd-8c6283e499ce, localhost:14957): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-frzglb4yoy.auto.internal" port: 14957 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-21 20:47:40,563 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.678902  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-frzglb4yoy.auto.internal:14957, location 1, ssl: False>]>
2025-11-21 20:47:40,563 - DEBUG - ydb.connection.678902  - close: Closing channel for endpoint localhost:14957
2025-11-21 20:47:40,826 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:40,827 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, f46d7ea0-d374-4379-b2f2-edb54b9f220e, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:47:40,828 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, f46d7ea0-d374-4379-b2f2-edb54b9f220e, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:40,829 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, f46d7ea0-d374-4379-b2f2-edb54b9f220e, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:44,635 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:54,682 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:47:54,683 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, fe90f3ac-081d-479d-aff2-e84118db893e, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:47:54,684 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, fe90f3ac-081d-479d-aff2-e84118db893e, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:54,685 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, fe90f3ac-081d-479d-aff2-e84118db893e, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:57,030 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:07,043 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:07,044 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, fe3aaa9c-51ae-4559-b926-d274a0aff498, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:07,045 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, fe3aaa9c-51ae-4559-b926-d274a0aff498, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:07,051 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, fe3aaa9c-51ae-4559-b926-d274a0aff498, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:08,659 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:08,664 - INFO - root.678902  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1497984)
2025-11-21 20:48:08,664 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:08,665 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 835bce17-8d64-47c8-b0dc-b37736f775d9, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:08,666 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 835bce17-8d64-47c8-b0dc-b37736f775d9, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" 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_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:08,668 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 835bce17-8d64-47c8-b0dc-b37736f775d9, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:09,743 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:09,744 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:09,745 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 89d70017-abac-4229-9e1f-23f6f072fdaf, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:09,746 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 89d70017-abac-4229-9e1f-23f6f072fdaf, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:09,749 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 89d70017-abac-4229-9e1f-23f6f072fdaf, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:11,345 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:21,350 - DEBUG - ydb.query.pool.678902  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:21,351 - DEBUG - ydb.connection.678902  - _prepare_call: RpcState(ExecuteQuery, 98448316-c4bf-4e28-ac50-d3863844bf03, ghrun-frzglb4yoy.auto.internal:14957): creating call state
2025-11-21 20:48:21,352 - DEBUG - ydb.connection.678902  - _log_request: RpcState(ExecuteQuery, 98448316-c4bf-4e28-ac50-d3863844bf03, ghrun-frzglb4yoy.auto.internal:14957): request = { session_id: "ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:48:21,353 - DEBUG - ydb.connection.678902  - _log_response: RpcState(ExecuteQuery, 98448316-c4bf-4e28-ac50-d3863844bf03, ghrun-frzglb4yoy.auto.internal:14957): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:48:22,613 - DEBUG - ydb.query.pool.678902  - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI2ZjMxNWMtNzI1NjVjZTctMzU2NzBlYy0zNWQwYzVjZQ==
2025-11-21 20:48:22,618 - INFO - root.678902  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1497984)
2025-11-21 20:48:22,619 - INFO - root.678902  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_16_compression` 8092160 / 1497984: 5.402033666581218
2025-11-21 20:48:22,621 - INFO - ya.test.678902  - pytest_runtest_teardown: Test teardown
