2025-12-07 19:48:04,311 - INFO - ya.test.35513  - pytest_runtest_setup: ####################################################################################################
2025-12-07 19:48:04,311 - INFO - ya.test.35513  - pytest_runtest_setup: test_all_supported_compression[zstd_9_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 9]
2025-12-07 19:48:04,312 - INFO - ya.test.35513  - pytest_runtest_setup: ####################################################################################################
2025-12-07 19:48:04,312 - INFO - ya.test.35513  - pytest_runtest_setup: Test setup
2025-12-07 19:48:04,322 - INFO - ya.test.35513  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_9_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 9])
2025-12-07 19:48:04,323 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:04,324 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 3f16f26a-56d4-4fde-aac4-8540cc7c41e1, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:04,325 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 3f16f26a-56d4-4fde-aac4-8540cc7c41e1, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_9_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 9)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:04,335 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 3f16f26a-56d4-4fde-aac4-8540cc7c41e1, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:09,138 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.35513  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 19:48:09,139 - DEBUG - ydb.connection.35513  - 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-12-07 19:48:09,196 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.35513  - context_resolve: Resolving endpoints for database /Root
2025-12-07 19:48:09,197 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ListEndpoints, d1cb49aa-355b-47f2-8532-393543a06f44, localhost:11283): creating call state
2025-12-07 19:48:09,197 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ListEndpoints, d1cb49aa-355b-47f2-8532-393543a06f44, localhost:11283): request = { database: "/Root" }
2025-12-07 19:48:09,632 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ListEndpoints, d1cb49aa-355b-47f2-8532-393543a06f44, localhost:11283): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7gzmsyrbqy.auto.internal" port: 11283 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-12-07 19:48:09,634 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.35513  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7gzmsyrbqy.auto.internal:11283, location 1, ssl: False>]>
2025-12-07 19:48:09,634 - DEBUG - ydb.connection.35513  - close: Closing channel for endpoint localhost:11283
2025-12-07 19:48:10,354 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:10,355 - INFO - __tests__.alter_compression.35513  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_9_compression created
2025-12-07 19:48:10,355 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:10,356 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, a88099c7-b412-4ea5-84fe-e3584104a9ed, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:10,356 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, a88099c7-b412-4ea5-84fe-e3584104a9ed, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_9_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:10,362 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, a88099c7-b412-4ea5-84fe-e3584104a9ed, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:11,484 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:11,485 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:11,486 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 8691405f-0ee4-43c7-824a-8f1eaaf5db25, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:11,487 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 8691405f-0ee4-43c7-824a-8f1eaaf5db25, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:11,500 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 8691405f-0ee4-43c7-824a-8f1eaaf5db25, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:14,419 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:14,420 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:14,421 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 4ff1a0fa-e773-4b2e-aedd-c31808ebd17e, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:14,422 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 4ff1a0fa-e773-4b2e-aedd-c31808ebd17e, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:14,461 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 4ff1a0fa-e773-4b2e-aedd-c31808ebd17e, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:15,538 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:15,539 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:15,540 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 3c81e8cd-db16-4594-8ef0-454871d117c8, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:15,541 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 3c81e8cd-db16-4594-8ef0-454871d117c8, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:15,545 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 3c81e8cd-db16-4594-8ef0-454871d117c8, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:18,404 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:18,405 - INFO - __tests__.alter_compression.35513  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318048, 'BlobCount': 128}}
2025-12-07 19:48:18,405 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:18,406 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 525ad692-021b-4513-9f5d-2f753f2fd690, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:18,407 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 525ad692-021b-4513-9f5d-2f753f2fd690, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:18,418 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 525ad692-021b-4513-9f5d-2f753f2fd690, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:20,829 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:20,830 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:20,831 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, d9981d78-6e6f-4cac-898f-6e680a522dac, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:20,832 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, d9981d78-6e6f-4cac-898f-6e680a522dac, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:20,833 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, d9981d78-6e6f-4cac-898f-6e680a522dac, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:21,122 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:21,123 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:21,124 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 71394516-961d-4288-8471-dd3596e31538, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:21,125 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 71394516-961d-4288-8471-dd3596e31538, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:21,148 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 71394516-961d-4288-8471-dd3596e31538, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:21,490 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:21,495 - INFO - __tests__.alter_compression.35513  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637344, 'BlobCount': 256}}
2025-12-07 19:48:21,495 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:21,496 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, dbd60056-bd8c-464a-a95a-e6d6bc7a325d, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:21,497 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, dbd60056-bd8c-464a-a95a-e6d6bc7a325d, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:21,507 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, dbd60056-bd8c-464a-a95a-e6d6bc7a325d, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:23,515 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:23,516 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:23,516 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, e57a5c9a-6860-4e14-82b5-dc8322cc062b, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:23,517 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, e57a5c9a-6860-4e14-82b5-dc8322cc062b, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:23,519 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, e57a5c9a-6860-4e14-82b5-dc8322cc062b, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:24,049 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:24,050 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:24,051 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 49650d54-ee97-4c0d-81e6-b317bd81fcd5, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:24,052 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 49650d54-ee97-4c0d-81e6-b317bd81fcd5, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:24,053 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 49650d54-ee97-4c0d-81e6-b317bd81fcd5, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:24,674 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:24,675 - INFO - __tests__.alter_compression.35513  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956384, 'BlobCount': 384}}
2025-12-07 19:48:24,675 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:24,676 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 72d0b672-bc9d-4837-b53b-bb7b17d2ac1b, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:24,677 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 72d0b672-bc9d-4837-b53b-bb7b17d2ac1b, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:24,678 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 72d0b672-bc9d-4837-b53b-bb7b17d2ac1b, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:26,351 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:26,351 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:26,352 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 9406fa6d-bd23-4216-b57e-5c3192a1e7fe, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:26,353 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 9406fa6d-bd23-4216-b57e-5c3192a1e7fe, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:26,354 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 9406fa6d-bd23-4216-b57e-5c3192a1e7fe, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:26,912 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:26,912 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:26,913 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 2d17fda7-56c1-41ad-93db-016dbd5367f2, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:26,914 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 2d17fda7-56c1-41ad-93db-016dbd5367f2, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:26,915 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 2d17fda7-56c1-41ad-93db-016dbd5367f2, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:27,507 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:27,507 - INFO - __tests__.alter_compression.35513  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275744, 'BlobCount': 512}}
2025-12-07 19:48:27,508 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:27,509 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, e9e809ed-c548-4f2c-84aa-3c3b324ee742, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:27,509 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, e9e809ed-c548-4f2c-84aa-3c3b324ee742, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:27,511 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, e9e809ed-c548-4f2c-84aa-3c3b324ee742, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:29,484 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:29,485 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:29,486 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 16e4513e-c6d6-428a-80f6-aff496b6814d, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:29,487 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 16e4513e-c6d6-428a-80f6-aff496b6814d, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:29,488 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 16e4513e-c6d6-428a-80f6-aff496b6814d, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:29,881 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:29,882 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:29,882 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, cdd418e4-37a6-4b09-9590-76e6024c869d, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:29,883 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, cdd418e4-37a6-4b09-9590-76e6024c869d, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:29,884 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, cdd418e4-37a6-4b09-9590-76e6024c869d, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:30,395 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:30,396 - INFO - __tests__.alter_compression.35513  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594704, 'BlobCount': 640}}
2025-12-07 19:48:30,396 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:30,397 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 03673f9a-5615-4a6e-b1af-e9fd6780d5b1, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:30,398 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 03673f9a-5615-4a6e-b1af-e9fd6780d5b1, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:30,399 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 03673f9a-5615-4a6e-b1af-e9fd6780d5b1, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:32,915 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:32,916 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:32,917 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 7ca109cb-6c3a-4399-8a95-7b27fa165583, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:32,918 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 7ca109cb-6c3a-4399-8a95-7b27fa165583, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:32,930 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 7ca109cb-6c3a-4399-8a95-7b27fa165583, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:33,469 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:33,470 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:33,471 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, dd3ed036-ea48-452f-ab9d-90288e337e6c, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:33,472 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, dd3ed036-ea48-452f-ab9d-90288e337e6c, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:33,473 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, dd3ed036-ea48-452f-ab9d-90288e337e6c, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:34,211 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:34,212 - INFO - __tests__.alter_compression.35513  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913888, 'BlobCount': 768}}
2025-12-07 19:48:34,213 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:34,214 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 29f49de5-f3f6-47fc-96d9-02cba6d6f458, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:34,214 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 29f49de5-f3f6-47fc-96d9-02cba6d6f458, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:34,216 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 29f49de5-f3f6-47fc-96d9-02cba6d6f458, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:37,120 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:37,120 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:37,121 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 53aa1c40-86bc-4814-9085-ccf242f9f9ab, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:37,122 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 53aa1c40-86bc-4814-9085-ccf242f9f9ab, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:37,123 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 53aa1c40-86bc-4814-9085-ccf242f9f9ab, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:37,651 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:37,651 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:37,652 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 2be92f85-4302-43cc-8e3f-5f01faf128bd, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:37,653 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 2be92f85-4302-43cc-8e3f-5f01faf128bd, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:37,656 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 2be92f85-4302-43cc-8e3f-5f01faf128bd, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:38,762 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:38,762 - INFO - __tests__.alter_compression.35513  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233024, 'BlobCount': 896}}
2025-12-07 19:48:38,763 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:38,763 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 2cae1c87-20cd-46a4-bb7a-51ce49aefc20, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:38,764 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 2cae1c87-20cd-46a4-bb7a-51ce49aefc20, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:38,765 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 2cae1c87-20cd-46a4-bb7a-51ce49aefc20, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:41,054 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:41,055 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:41,056 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 69b378b5-0208-425f-8a5b-cada722d9f43, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:41,056 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 69b378b5-0208-425f-8a5b-cada722d9f43, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:41,057 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 69b378b5-0208-425f-8a5b-cada722d9f43, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:41,455 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:41,456 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:41,457 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, badc2755-7729-4ae4-b553-620817fd57e4, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:41,457 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, badc2755-7729-4ae4-b553-620817fd57e4, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:41,459 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, badc2755-7729-4ae4-b553-620817fd57e4, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:42,881 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:42,881 - INFO - __tests__.alter_compression.35513  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552512, 'BlobCount': 1024}}
2025-12-07 19:48:42,882 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:42,883 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 83bdfd32-f961-4044-b149-15539c5e7041, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:42,883 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 83bdfd32-f961-4044-b149-15539c5e7041, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:42,886 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 83bdfd32-f961-4044-b149-15539c5e7041, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:45,536 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:45,537 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:45,537 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, a7faf924-38f7-48d2-b6cd-a9a7cb9d1bca, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:45,538 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, a7faf924-38f7-48d2-b6cd-a9a7cb9d1bca, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:45,543 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, a7faf924-38f7-48d2-b6cd-a9a7cb9d1bca, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:45,938 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:45,939 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:45,939 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 12f66282-0e9a-466c-bc07-4b339079510a, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:45,940 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 12f66282-0e9a-466c-bc07-4b339079510a, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:45,949 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 12f66282-0e9a-466c-bc07-4b339079510a, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:47,127 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:47,128 - INFO - __tests__.alter_compression.35513  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871520, 'BlobCount': 1152}}
2025-12-07 19:48:47,128 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:47,129 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, ad503a60-8395-4d75-bb50-e99fface0df9, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:47,140 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, ad503a60-8395-4d75-bb50-e99fface0df9, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:47,149 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, ad503a60-8395-4d75-bb50-e99fface0df9, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:50,143 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:50,144 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:50,145 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, b995f9c9-3247-45c1-a067-3276a21bb435, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:50,146 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, b995f9c9-3247-45c1-a067-3276a21bb435, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:50,147 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, b995f9c9-3247-45c1-a067-3276a21bb435, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:51,704 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:51,705 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:51,706 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, dc5cd8c3-0809-4c7f-8ccb-49b057990e36, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:51,707 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, dc5cd8c3-0809-4c7f-8ccb-49b057990e36, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:51,726 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, dc5cd8c3-0809-4c7f-8ccb-49b057990e36, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:52,770 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:52,783 - INFO - __tests__.alter_compression.35513  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_9_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191024, 'BlobCount': 1280}}
2025-12-07 19:48:52,783 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:52,784 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, ada49736-ccaf-4bb2-bf58-a07a184b36f6, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:52,785 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, ada49736-ccaf-4bb2-bf58-a07a184b36f6, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_9_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:52,797 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, ada49736-ccaf-4bb2-bf58-a07a184b36f6, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:54,398 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:54,399 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:54,407 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, a1f02b41-ecdc-4f22-b4cc-58313e414193, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:54,408 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, a1f02b41-ecdc-4f22-b4cc-58313e414193, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:54,409 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, a1f02b41-ecdc-4f22-b4cc-58313e414193, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:54,845 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:54,846 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:54,847 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, cfc7c74a-b38b-43cd-8298-56b1adf05313, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:54,848 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, cfc7c74a-b38b-43cd-8298-56b1adf05313, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:54,855 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, cfc7c74a-b38b-43cd-8298-56b1adf05313, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:48:55,447 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:55,448 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:48:55,449 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 7312252d-fe54-4c4b-84bb-4c0efc46dc32, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:48:55,450 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 7312252d-fe54-4c4b-84bb-4c0efc46dc32, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:48:55,451 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 7312252d-fe54-4c4b-84bb-4c0efc46dc32, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:00,095 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:10,242 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:10,243 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, fedc739d-e9da-4576-a64d-f59928fea328, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:49:10,244 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, fedc739d-e9da-4576-a64d-f59928fea328, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:10,247 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, fedc739d-e9da-4576-a64d-f59928fea328, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:15,660 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:25,718 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:25,719 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 4fdf1d85-7f77-45e6-a878-0b1e5c2ae442, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:49:25,720 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 4fdf1d85-7f77-45e6-a878-0b1e5c2ae442, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:25,722 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 4fdf1d85-7f77-45e6-a878-0b1e5c2ae442, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:27,027 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:36,086 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.35513  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 19:49:36,087 - DEBUG - ydb.connection.35513  - 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-12-07 19:49:36,104 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.35513  - context_resolve: Resolving endpoints for database /Root
2025-12-07 19:49:36,104 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ListEndpoints, e015617a-b641-4c94-87d4-1c022373b41a, localhost:11283): creating call state
2025-12-07 19:49:36,105 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ListEndpoints, e015617a-b641-4c94-87d4-1c022373b41a, localhost:11283): request = { database: "/Root" }
2025-12-07 19:49:36,112 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ListEndpoints, e015617a-b641-4c94-87d4-1c022373b41a, localhost:11283): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7gzmsyrbqy.auto.internal" port: 11283 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-12-07 19:49:36,114 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.35513  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7gzmsyrbqy.auto.internal:11283, location 1, ssl: False>]>
2025-12-07 19:49:36,122 - DEBUG - ydb.connection.35513  - close: Closing channel for endpoint localhost:11283
2025-12-07 19:49:37,034 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:37,035 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 32f6ecd6-2264-4282-a29b-26f8c6569a50, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:49:37,036 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 32f6ecd6-2264-4282-a29b-26f8c6569a50, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:37,037 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 32f6ecd6-2264-4282-a29b-26f8c6569a50, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:38,091 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:48,096 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:48,097 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, ad2e3b7b-bae7-484c-af73-11908da8eddf, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:49:48,098 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, ad2e3b7b-bae7-484c-af73-11908da8eddf, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:48,099 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, ad2e3b7b-bae7-484c-af73-11908da8eddf, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:49,308 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:49,312 - INFO - root.35513  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_9_compression`, volumes `value` (8000000, 1465240)
2025-12-07 19:49:49,313 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:49,314 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, bf138aee-d981-47fa-9fa0-2c9e180ca87c, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:49:49,323 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, bf138aee-d981-47fa-9fa0-2c9e180ca87c, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" 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_9_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:49,325 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, bf138aee-d981-47fa-9fa0-2c9e180ca87c, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:49,722 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:49,723 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:49:49,724 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, 1ebc5cbe-4c6b-4707-99ca-4240fd7abec3, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:49:49,725 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, 1ebc5cbe-4c6b-4707-99ca-4240fd7abec3, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:49:49,727 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, 1ebc5cbe-4c6b-4707-99ca-4240fd7abec3, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:49:50,881 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:50:00,885 - DEBUG - ydb.query.pool.35513  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:50:00,886 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(ExecuteQuery, c67193ac-e5e2-49b8-aa13-ae0473104705, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:50:00,887 - DEBUG - ydb.connection.35513  - _log_request: RpcState(ExecuteQuery, c67193ac-e5e2-49b8-aa13-ae0473104705, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_9_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 19:50:00,890 - DEBUG - ydb.connection.35513  - _log_response: RpcState(ExecuteQuery, c67193ac-e5e2-49b8-aa13-ae0473104705, ghrun-7gzmsyrbqy.auto.internal:11283): response = { <_MultiThreadedRendezvous object> }
2025-12-07 19:50:02,424 - DEBUG - ydb.query.pool.35513  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=
2025-12-07 19:50:02,428 - INFO - root.35513  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_9_compression`, volumes `value` (8000000, 1465240)
2025-12-07 19:50:02,428 - INFO - root.35513  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_9_compression` 8009216 / 1465240: 5.466146160355983
2025-12-07 19:50:02,437 - INFO - ya.test.35513  - pytest_runtest_teardown: Test teardown
2025-12-07 19:50:02,439 - DEBUG - ydb.connection.35513  - _prepare_call: RpcState(DeleteSession, 5edba16b-f3c3-47c4-94a7-51b1ada235e9, ghrun-7gzmsyrbqy.auto.internal:11283): creating call state
2025-12-07 19:50:02,440 - DEBUG - ydb.connection.35513  - _log_request: RpcState(DeleteSession, 5edba16b-f3c3-47c4-94a7-51b1ada235e9, ghrun-7gzmsyrbqy.auto.internal:11283): request = { session_id: "ydb://session/3?node_id=1&id=OTNiYWJiNmEtZDFiNmFhYmQtMzg0YTA3N2MtMjY0MDc1M2M=" }
2025-12-07 19:50:02,455 - DEBUG - ydb.connection.35513  - _log_response: RpcState(DeleteSession, 5edba16b-f3c3-47c4-94a7-51b1ada235e9, ghrun-7gzmsyrbqy.auto.internal:11283): response = { status: SUCCESS }
2025-12-07 19:50:02,457 - DEBUG - ydb.query.pool.35513  - stop: All session were deleted.
2025-12-07 19:50:02,462 - DEBUG - ydb.topic.35513  - close: Close topic client
2025-12-07 19:50:02,463 - DEBUG - ydb.topic.35513  - close: Topic client was closed
2025-12-07 19:50:02,463 - DEBUG - ydb.connection.35513  - close: Closing channel for endpoint localhost:11283
2025-12-07 19:50:02,465 - DEBUG - ydb.connection.35513  - close: Closing channel for endpoint ghrun-7gzmsyrbqy.auto.internal:11283
2025-12-07 19:50:02,467 - INFO - ydb.pool.Discovery.35513  - run: Successfully terminated discovery process
2025-12-07 19:50:02,470 - DEBUG - ydb.connection.35513  - _rpc_error_handler: RpcState(AttachSession, a825625b-e7c7-4504-a9f7-ec1039a10cb9, ghrun-7gzmsyrbqy.auto.internal:11283): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-07 19:50:19,494 - INFO - ydb.tests.library.harness.kikimr_runner.35513  - stop: Stopped node localhost:11283/1
2025-12-07 19:50:19,507 - DEBUG - library.python.filelock.35513  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13746
2025-12-07 19:50:19,508 - DEBUG - library.python.filelock.35513  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64255
2025-12-07 19:50:19,508 - DEBUG - library.python.filelock.35513  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5659
2025-12-07 19:50:19,509 - DEBUG - library.python.filelock.35513  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11283
2025-12-07 19:50:19,509 - DEBUG - library.python.filelock.35513  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20919
