2025-11-19 11:24:57,897 - INFO - ya.test.419682  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:24:57,897 - INFO - ya.test.419682  - pytest_runtest_setup: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14]
2025-11-19 11:24:57,897 - INFO - ya.test.419682  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:24:57,898 - INFO - ya.test.419682  - pytest_runtest_setup: Test setup
2025-11-19 11:24:57,900 - INFO - ya.test.419682  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_14_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 14])
2025-11-19 11:24:57,900 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:57,901 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 69120b33-9553-40ca-ac7a-d5a9b948e77c, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:57,902 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 69120b33-9553-40ca-ac7a-d5a9b948e77c, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_14_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 14)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:57,903 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 69120b33-9553-40ca-ac7a-d5a9b948e77c, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:02,343 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:02,344 - INFO - __tests__.alter_compression.419682  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_14_compression created
2025-11-19 11:25:02,344 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:02,345 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, fc285a64-839a-4366-97e5-3e358a8ad5e6, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:02,345 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, fc285a64-839a-4366-97e5-3e358a8ad5e6, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:02,347 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, fc285a64-839a-4366-97e5-3e358a8ad5e6, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:04,245 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:04,246 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:04,247 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f618de2e-a46c-41d6-a16f-bb87eae1cf99, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:04,248 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f618de2e-a46c-41d6-a16f-bb87eae1cf99, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:04,253 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f618de2e-a46c-41d6-a16f-bb87eae1cf99, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:09,628 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:09,629 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:09,630 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 7d140e56-8897-40b0-abb4-76441261b3fc, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:09,631 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 7d140e56-8897-40b0-abb4-76441261b3fc, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:09,632 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 7d140e56-8897-40b0-abb4-76441261b3fc, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:11,284 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:11,285 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:11,286 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 64df4dc7-534f-47a4-b8ac-c8c0c504fdca, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:11,287 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 64df4dc7-534f-47a4-b8ac-c8c0c504fdca, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:11,289 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 64df4dc7-534f-47a4-b8ac-c8c0c504fdca, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:14,873 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:14,874 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-11-19 11:25:14,874 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:14,875 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, c30348f2-704c-442b-bed4-182066cf8597, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:14,876 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, c30348f2-704c-442b-bed4-182066cf8597, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:14,877 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, c30348f2-704c-442b-bed4-182066cf8597, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:20,209 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:25:20,210 - DEBUG - ydb.connection.419682  - 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-19 11:25:20,260 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:25:20,261 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ListEndpoints, e8bfdb25-c33c-4a2b-abd1-46ad4608138a, localhost:10572): creating call state
2025-11-19 11:25:20,262 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ListEndpoints, e8bfdb25-c33c-4a2b-abd1-46ad4608138a, localhost:10572): request = { database: "/Root" }
2025-11-19 11:25:20,281 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ListEndpoints, e8bfdb25-c33c-4a2b-abd1-46ad4608138a, localhost:10572): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 10572 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-19 11:25:20,282 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:10572, location 1, ssl: False>]>
2025-11-19 11:25:20,283 - DEBUG - ydb.connection.419682  - close: Closing channel for endpoint localhost:10572
2025-11-19 11:25:21,063 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:21,064 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:21,065 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, e7231ae7-abc7-47b7-946f-142a9083d442, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:21,066 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, e7231ae7-abc7-47b7-946f-142a9083d442, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:21,067 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, e7231ae7-abc7-47b7-946f-142a9083d442, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:22,178 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:22,179 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:22,180 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f09ccc80-01d2-4b2e-b142-19d339fc10b0, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:22,181 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f09ccc80-01d2-4b2e-b142-19d339fc10b0, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:22,182 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f09ccc80-01d2-4b2e-b142-19d339fc10b0, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:23,160 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:23,161 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-11-19 11:25:23,161 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:23,162 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, dcd6a03f-af5b-48b7-a526-306d0d9817a4, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:23,163 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, dcd6a03f-af5b-48b7-a526-306d0d9817a4, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:23,164 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, dcd6a03f-af5b-48b7-a526-306d0d9817a4, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:28,480 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:28,481 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:28,482 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, a5ad6185-4fcb-4a69-924d-bf46db101932, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:28,483 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, a5ad6185-4fcb-4a69-924d-bf46db101932, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:28,485 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, a5ad6185-4fcb-4a69-924d-bf46db101932, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:28,985 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:28,986 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:28,987 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 1fe67f15-5fad-4d1e-8a2b-5b8025c442f2, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:28,989 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 1fe67f15-5fad-4d1e-8a2b-5b8025c442f2, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:28,990 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 1fe67f15-5fad-4d1e-8a2b-5b8025c442f2, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:30,409 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:30,409 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-11-19 11:25:30,410 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:30,411 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 31fd7486-a818-4946-8ed0-0b5d4bb47f84, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:30,419 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 31fd7486-a818-4946-8ed0-0b5d4bb47f84, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:30,421 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 31fd7486-a818-4946-8ed0-0b5d4bb47f84, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:35,801 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:35,802 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:35,803 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, adf66d61-7c38-485c-86d7-063bb89a0e0b, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:35,804 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, adf66d61-7c38-485c-86d7-063bb89a0e0b, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:35,807 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, adf66d61-7c38-485c-86d7-063bb89a0e0b, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:36,378 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:36,379 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:36,380 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 0c9b2cfa-ba0b-4c80-8b4a-f8c7cae105dd, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:36,381 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 0c9b2cfa-ba0b-4c80-8b4a-f8c7cae105dd, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:36,383 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 0c9b2cfa-ba0b-4c80-8b4a-f8c7cae105dd, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:37,217 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:37,218 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-11-19 11:25:37,218 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:37,219 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, a8702056-e4b8-4ae4-acbd-eaabfa55c428, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:37,220 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, a8702056-e4b8-4ae4-acbd-eaabfa55c428, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:37,222 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, a8702056-e4b8-4ae4-acbd-eaabfa55c428, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:42,560 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:42,561 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:42,561 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 8ad4c389-dce6-4575-a340-702e100c8f08, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:42,562 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 8ad4c389-dce6-4575-a340-702e100c8f08, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:42,568 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 8ad4c389-dce6-4575-a340-702e100c8f08, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:43,379 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:43,380 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:43,381 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 834d16ed-1e5a-496c-a096-acc72f313ad1, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:43,382 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 834d16ed-1e5a-496c-a096-acc72f313ad1, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:43,395 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 834d16ed-1e5a-496c-a096-acc72f313ad1, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:44,952 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:44,953 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-11-19 11:25:44,954 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:44,955 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 59431c05-43e2-4f51-9b56-b9b9558cfcac, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:44,964 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 59431c05-43e2-4f51-9b56-b9b9558cfcac, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:44,966 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 59431c05-43e2-4f51-9b56-b9b9558cfcac, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:50,750 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:50,751 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:50,751 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f62bb2b3-19f2-480b-bead-be453db1b919, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:50,752 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f62bb2b3-19f2-480b-bead-be453db1b919, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:50,756 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f62bb2b3-19f2-480b-bead-be453db1b919, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:51,310 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:51,311 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:51,312 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 44782d9c-3f28-4c1d-af6f-fde8dfb94e9c, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:51,313 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 44782d9c-3f28-4c1d-af6f-fde8dfb94e9c, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:51,315 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 44782d9c-3f28-4c1d-af6f-fde8dfb94e9c, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:52,641 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:52,641 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-11-19 11:25:52,642 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:52,643 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 12f12167-77d7-4117-9254-69acccf3a2d0, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:52,644 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 12f12167-77d7-4117-9254-69acccf3a2d0, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:52,646 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 12f12167-77d7-4117-9254-69acccf3a2d0, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:56,644 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:56,645 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:56,646 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, fbb50501-da7e-4e01-85ab-88c374e34b0e, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:56,647 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, fbb50501-da7e-4e01-85ab-88c374e34b0e, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:56,649 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, fbb50501-da7e-4e01-85ab-88c374e34b0e, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:57,150 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:57,151 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:57,152 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 71069191-1293-41c0-b774-f0db39c2e17d, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:57,153 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 71069191-1293-41c0-b774-f0db39c2e17d, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:57,158 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 71069191-1293-41c0-b774-f0db39c2e17d, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:25:58,178 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:58,179 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-11-19 11:25:58,179 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:25:58,180 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 9b91ff64-e3b1-4fd2-88b0-b3f5b97546de, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:25:58,181 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 9b91ff64-e3b1-4fd2-88b0-b3f5b97546de, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:25:58,183 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 9b91ff64-e3b1-4fd2-88b0-b3f5b97546de, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:02,512 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:02,513 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:02,514 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 5ba1ba43-bc48-452c-9cca-b23fdcc9b516, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:02,515 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 5ba1ba43-bc48-452c-9cca-b23fdcc9b516, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:02,517 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 5ba1ba43-bc48-452c-9cca-b23fdcc9b516, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:03,103 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:03,104 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:03,105 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 13d18729-0e71-4936-bc6a-717f6ae8fdf5, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:03,106 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 13d18729-0e71-4936-bc6a-717f6ae8fdf5, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:03,108 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 13d18729-0e71-4936-bc6a-717f6ae8fdf5, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:03,967 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:03,968 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-11-19 11:26:03,970 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:03,971 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 74b7c19d-1253-4e00-b8da-1981f30a24a6, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:03,973 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 74b7c19d-1253-4e00-b8da-1981f30a24a6, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:03,974 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 74b7c19d-1253-4e00-b8da-1981f30a24a6, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:08,552 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:08,553 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:08,554 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 00ff5cb8-3b0d-4b6f-9cc4-4310d05fb6fb, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:08,555 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 00ff5cb8-3b0d-4b6f-9cc4-4310d05fb6fb, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:08,557 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 00ff5cb8-3b0d-4b6f-9cc4-4310d05fb6fb, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:09,063 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:09,064 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:09,065 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f32f9f68-608d-4c9c-bf4b-957783579928, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:09,066 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f32f9f68-608d-4c9c-bf4b-957783579928, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:09,077 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f32f9f68-608d-4c9c-bf4b-957783579928, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:10,209 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:10,210 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-11-19 11:26:10,210 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:10,211 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 08183ab9-6aa6-481a-99bd-2687334d59f0, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:10,220 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 08183ab9-6aa6-481a-99bd-2687334d59f0, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_14_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:10,222 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 08183ab9-6aa6-481a-99bd-2687334d59f0, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:13,601 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:13,602 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:13,603 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 828e9541-56e4-4e28-84c1-e999b3b46944, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:13,604 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 828e9541-56e4-4e28-84c1-e999b3b46944, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:13,606 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 828e9541-56e4-4e28-84c1-e999b3b46944, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:14,250 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:14,251 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:14,252 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, c07985cd-da79-47ff-87c8-9c2e2ac48c04, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:14,253 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, c07985cd-da79-47ff-87c8-9c2e2ac48c04, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:14,255 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, c07985cd-da79-47ff-87c8-9c2e2ac48c04, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:15,265 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:15,266 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_14_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-11-19 11:26:15,267 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:15,272 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 42dd70f2-e4b6-46f9-a3e2-7dd04d8ac587, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:15,273 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 42dd70f2-e4b6-46f9-a3e2-7dd04d8ac587, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_14_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:15,274 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 42dd70f2-e4b6-46f9-a3e2-7dd04d8ac587, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:16,951 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:16,953 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:16,955 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 7bc5e088-2d06-41de-9edc-c56caec436fd, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:16,956 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 7bc5e088-2d06-41de-9edc-c56caec436fd, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:16,957 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 7bc5e088-2d06-41de-9edc-c56caec436fd, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:17,332 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:17,333 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:17,334 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 46721aa9-a30d-4ee4-9d75-386e355aa5f4, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:17,335 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 46721aa9-a30d-4ee4-9d75-386e355aa5f4, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:17,337 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 46721aa9-a30d-4ee4-9d75-386e355aa5f4, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:17,665 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:17,665 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:17,666 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 0913b2d0-9f98-4c8d-b879-c39b0bb4792d, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:17,667 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 0913b2d0-9f98-4c8d-b879-c39b0bb4792d, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:17,668 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 0913b2d0-9f98-4c8d-b879-c39b0bb4792d, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:22,156 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:32,201 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:32,202 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 4a1e468d-b6a2-42e2-b5e0-1f3bd2205d4f, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:32,203 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 4a1e468d-b6a2-42e2-b5e0-1f3bd2205d4f, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:32,207 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 4a1e468d-b6a2-42e2-b5e0-1f3bd2205d4f, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:36,815 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:36,919 - INFO - root.419682  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1596216)
2025-11-19 11:26:36,920 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:36,921 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, a480d2f7-8700-4a57-b93e-4ac3edf90909, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:36,922 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, a480d2f7-8700-4a57-b93e-4ac3edf90909, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:36,923 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, a480d2f7-8700-4a57-b93e-4ac3edf90909, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:37,300 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:37,301 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:37,301 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, b30110aa-f499-40c2-b98b-b9e55f496cd2, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:37,302 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, b30110aa-f499-40c2-b98b-b9e55f496cd2, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:37,305 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, b30110aa-f499-40c2-b98b-b9e55f496cd2, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:42,116 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:52,180 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:52,181 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, df2a8163-ed8f-4652-b808-8b4017d5f1ac, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:52,182 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, df2a8163-ed8f-4652-b808-8b4017d5f1ac, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_14_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:26:52,183 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, df2a8163-ed8f-4652-b808-8b4017d5f1ac, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:26:53,743 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:26:53,744 - DEBUG - ydb.connection.419682  - 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-19 11:26:53,889 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:26:53,889 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ListEndpoints, 934f5b46-86c1-4931-a90e-68f2cdedfc25, localhost:10572): creating call state
2025-11-19 11:26:53,890 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ListEndpoints, 934f5b46-86c1-4931-a90e-68f2cdedfc25, localhost:10572): request = { database: "/Root" }
2025-11-19 11:26:53,958 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ListEndpoints, 934f5b46-86c1-4931-a90e-68f2cdedfc25, localhost:10572): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 10572 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-19 11:26:53,959 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:10572, location 1, ssl: False>]>
2025-11-19 11:26:53,960 - DEBUG - ydb.connection.419682  - close: Closing channel for endpoint localhost:10572
2025-11-19 11:26:57,530 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:26:57,604 - INFO - root.419682  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_14_compression`, volumes `value` (8000000, 1596216)
2025-11-19 11:26:57,621 - INFO - root.419682  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_14_compression` 8092160 / 1596216: 5.069589579355175
2025-11-19 11:26:57,623 - INFO - ya.test.419682  - pytest_runtest_teardown: Test teardown
2025-11-19 11:26:57,624 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(DeleteSession, 355eb8fd-bec7-40dc-a854-82ffda49f9be, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:26:57,625 - DEBUG - ydb.connection.419682  - _log_request: RpcState(DeleteSession, 355eb8fd-bec7-40dc-a854-82ffda49f9be, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" }
2025-11-19 11:26:57,638 - DEBUG - ydb.connection.419682  - _log_response: RpcState(DeleteSession, 355eb8fd-bec7-40dc-a854-82ffda49f9be, ghrun-alsqrntpxi.auto.internal:10572): response = { status: SUCCESS }
2025-11-19 11:26:57,640 - DEBUG - ydb.query.pool.419682  - stop: All session were deleted.
2025-11-19 11:26:57,640 - DEBUG - ydb.topic.419682  - close: Close topic client
2025-11-19 11:26:57,640 - DEBUG - ydb.topic.419682  - close: Topic client was closed
2025-11-19 11:26:57,641 - DEBUG - ydb.connection.419682  - close: Closing channel for endpoint localhost:10572
2025-11-19 11:26:57,644 - DEBUG - ydb.connection.419682  - close: Closing channel for endpoint ghrun-alsqrntpxi.auto.internal:10572
2025-11-19 11:26:57,646 - INFO - ydb.pool.Discovery.419682  - run: Successfully terminated discovery process
2025-11-19 11:26:57,647 - DEBUG - ydb.connection.419682  - _rpc_error_handler: RpcState(AttachSession, c85ce214-9ee2-4ff4-981f-849100ff07a9, ghrun-alsqrntpxi.auto.internal:10572): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-19 11:27:19,682 - INFO - ydb.tests.library.harness.kikimr_runner.419682  - stop: Stopped node localhost:10572/1
2025-11-19 11:27:19,683 - DEBUG - library.python.filelock.419682  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8490
2025-11-19 11:27:19,683 - DEBUG - library.python.filelock.419682  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11876
2025-11-19 11:27:19,684 - DEBUG - library.python.filelock.419682  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6745
2025-11-19 11:27:19,684 - DEBUG - library.python.filelock.419682  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10572
2025-11-19 11:27:19,685 - DEBUG - library.python.filelock.419682  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23547
