2025-11-01 16:18:58,140 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:18:58,141 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16]
2025-11-01 16:18:58,141 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:18:58,141 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:18:58,144 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16])
2025-11-01 16:18:58,144 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:58,145 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b7a99900-3e62-496f-ae05-67bf15015cd2, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:58,146 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b7a99900-3e62-496f-ae05-67bf15015cd2, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_16_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 16)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:58,147 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b7a99900-3e62-496f-ae05-67bf15015cd2, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:01,443 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:01,447 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_16_compression created
2025-11-01 16:19:01,448 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:01,448 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d4c8bad-2e3b-41c4-825b-d41a47e63800, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:01,449 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d4c8bad-2e3b-41c4-825b-d41a47e63800, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:01,450 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d4c8bad-2e3b-41c4-825b-d41a47e63800, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:02,335 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:02,336 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:02,337 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b3a30d78-40bb-49c1-9018-011bd9c644d6, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:02,338 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b3a30d78-40bb-49c1-9018-011bd9c644d6, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:02,339 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b3a30d78-40bb-49c1-9018-011bd9c644d6, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:06,537 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:06,550 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:06,551 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 35fbed10-d4d9-4a85-b647-bc86cb30fb26, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:06,552 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 35fbed10-d4d9-4a85-b647-bc86cb30fb26, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:06,553 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 35fbed10-d4d9-4a85-b647-bc86cb30fb26, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:07,484 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:07,484 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:07,485 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a3eb0930-32d8-481a-bc7f-8ce9ba22c6a0, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:07,486 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a3eb0930-32d8-481a-bc7f-8ce9ba22c6a0, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:07,488 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a3eb0930-32d8-481a-bc7f-8ce9ba22c6a0, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:08,196 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:19:08,196 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:19:08,205 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:19:08,214 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 01220b66-b209-4cd8-a2f0-4ce2429e3ede, localhost:18143): creating call state
2025-11-01 16:19:08,215 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 01220b66-b209-4cd8-a2f0-4ce2429e3ede, localhost:18143): request = { database: "/Root" }
2025-11-01 16:19:08,220 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 01220b66-b209-4cd8-a2f0-4ce2429e3ede, localhost:18143): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 18143 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-01 16:19:08,239 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:18143, location 1, ssl: False>]>
2025-11-01 16:19:08,239 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:18143
2025-11-01 16:19:09,023 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:09,025 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-01 16:19:09,026 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:09,026 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4996c8c8-cdae-4c6e-ae07-dc4e7f196845, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:09,027 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4996c8c8-cdae-4c6e-ae07-dc4e7f196845, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:09,029 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4996c8c8-cdae-4c6e-ae07-dc4e7f196845, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:12,936 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:12,941 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:12,942 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b45a623-dae0-42b1-8c67-798e16ad10aa, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:12,943 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b45a623-dae0-42b1-8c67-798e16ad10aa, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:12,946 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b45a623-dae0-42b1-8c67-798e16ad10aa, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:13,296 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:13,315 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:13,315 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8b4d200-13ea-45eb-9ce6-bbacc96ecd57, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:13,317 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8b4d200-13ea-45eb-9ce6-bbacc96ecd57, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:13,318 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8b4d200-13ea-45eb-9ce6-bbacc96ecd57, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:13,772 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:13,773 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-01 16:19:13,773 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:13,774 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6850a55b-a9de-4dda-81c9-fcbe342c11d0, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:13,776 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6850a55b-a9de-4dda-81c9-fcbe342c11d0, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:13,777 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6850a55b-a9de-4dda-81c9-fcbe342c11d0, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:17,718 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:17,739 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:17,740 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 696576f9-f2df-4583-a81a-caba02981528, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:17,746 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 696576f9-f2df-4583-a81a-caba02981528, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:17,784 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 696576f9-f2df-4583-a81a-caba02981528, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:18,111 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:18,112 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:18,113 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db246a47-952e-4ce6-aea3-71f0b5169408, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:18,114 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db246a47-952e-4ce6-aea3-71f0b5169408, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:18,148 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db246a47-952e-4ce6-aea3-71f0b5169408, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:18,553 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:18,571 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-01 16:19:18,572 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:18,573 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ffa2c967-0d50-4950-9bf7-bcb3bf49d271, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:18,574 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ffa2c967-0d50-4950-9bf7-bcb3bf49d271, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:18,576 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ffa2c967-0d50-4950-9bf7-bcb3bf49d271, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:23,012 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:23,013 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:23,014 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6a7d9b20-8153-4c08-b2e3-ff8e8165ead9, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:23,024 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6a7d9b20-8153-4c08-b2e3-ff8e8165ead9, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:23,026 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6a7d9b20-8153-4c08-b2e3-ff8e8165ead9, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:23,466 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:23,467 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:23,468 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b010e27-fb35-4850-abc3-aa5760ebe095, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:23,469 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b010e27-fb35-4850-abc3-aa5760ebe095, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:23,479 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b010e27-fb35-4850-abc3-aa5760ebe095, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:24,091 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:24,108 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-01 16:19:24,109 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:24,110 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb875104-d368-496c-8204-0636e4b4c142, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:24,125 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb875104-d368-496c-8204-0636e4b4c142, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:24,127 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb875104-d368-496c-8204-0636e4b4c142, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:27,912 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:27,913 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:27,914 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb2bced2-1fb1-411e-9cc4-ea20802bac2d, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:27,915 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb2bced2-1fb1-411e-9cc4-ea20802bac2d, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:27,916 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb2bced2-1fb1-411e-9cc4-ea20802bac2d, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:28,219 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:28,220 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:28,221 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2f422e4d-5d7d-41c7-8e7e-0025bbda1744, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:28,222 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2f422e4d-5d7d-41c7-8e7e-0025bbda1744, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:28,224 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2f422e4d-5d7d-41c7-8e7e-0025bbda1744, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:28,704 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:28,705 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-01 16:19:28,706 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:28,707 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 295ae202-a71b-4924-9f1a-9816842ae2ef, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:28,732 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 295ae202-a71b-4924-9f1a-9816842ae2ef, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:28,752 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 295ae202-a71b-4924-9f1a-9816842ae2ef, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:32,359 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:32,360 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:32,361 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ddf7c5b4-a6d9-461e-af97-0d15593f348c, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:32,365 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ddf7c5b4-a6d9-461e-af97-0d15593f348c, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:32,371 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ddf7c5b4-a6d9-461e-af97-0d15593f348c, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:32,703 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:32,704 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:32,705 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 31d1807c-3d47-4cd6-841b-125cc9f250dc, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:32,707 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 31d1807c-3d47-4cd6-841b-125cc9f250dc, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:32,708 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 31d1807c-3d47-4cd6-841b-125cc9f250dc, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:33,231 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:33,232 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-01 16:19:33,233 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:33,233 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec589f86-1112-4d70-9d30-9f299136184c, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:33,234 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec589f86-1112-4d70-9d30-9f299136184c, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:33,236 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec589f86-1112-4d70-9d30-9f299136184c, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:37,102 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:37,102 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:37,103 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1accf6f6-3387-415a-9a61-d3093b1dd1f5, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:37,104 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1accf6f6-3387-415a-9a61-d3093b1dd1f5, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:37,105 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1accf6f6-3387-415a-9a61-d3093b1dd1f5, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:37,556 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:37,557 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:37,558 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd78c1bd-8328-4289-82b0-42a6d4f05857, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:37,559 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd78c1bd-8328-4289-82b0-42a6d4f05857, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:37,563 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd78c1bd-8328-4289-82b0-42a6d4f05857, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:38,036 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:38,037 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-01 16:19:38,037 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:38,038 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1a0d31df-9ebe-41e4-a465-644bb41a0a2b, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:38,039 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1a0d31df-9ebe-41e4-a465-644bb41a0a2b, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:38,041 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1a0d31df-9ebe-41e4-a465-644bb41a0a2b, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:41,667 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:41,668 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:41,669 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9002df91-2cb6-4a7a-836a-492cd8798621, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:41,670 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9002df91-2cb6-4a7a-836a-492cd8798621, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:41,671 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9002df91-2cb6-4a7a-836a-492cd8798621, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:41,904 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:41,905 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:41,906 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a116fe41-16f1-4909-b86c-8475ba3bada4, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:41,907 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a116fe41-16f1-4909-b86c-8475ba3bada4, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:41,908 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a116fe41-16f1-4909-b86c-8475ba3bada4, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:42,389 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:42,390 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-01 16:19:42,390 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:42,391 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ebbd983-b6d8-401a-9022-8cdde2672acc, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:42,391 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ebbd983-b6d8-401a-9022-8cdde2672acc, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:42,392 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ebbd983-b6d8-401a-9022-8cdde2672acc, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:45,725 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:45,725 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:45,726 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f3eb7e22-9757-4f60-b2e8-91d6834b7abf, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:45,727 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f3eb7e22-9757-4f60-b2e8-91d6834b7abf, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:45,728 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f3eb7e22-9757-4f60-b2e8-91d6834b7abf, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:45,972 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:45,973 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:45,974 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b9c83918-b2ed-41a7-b524-37900e13bcd6, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:45,975 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b9c83918-b2ed-41a7-b524-37900e13bcd6, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:45,977 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b9c83918-b2ed-41a7-b524-37900e13bcd6, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:46,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:46,489 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-01 16:19:46,490 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:46,490 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 28f43d4d-5d9a-44c1-86bc-1a8a28d06067, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:46,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 28f43d4d-5d9a-44c1-86bc-1a8a28d06067, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:46,493 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 28f43d4d-5d9a-44c1-86bc-1a8a28d06067, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:50,086 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:50,087 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:50,087 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7af939d4-1378-427a-83ed-8bdac426de18, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:50,088 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7af939d4-1378-427a-83ed-8bdac426de18, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:50,089 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7af939d4-1378-427a-83ed-8bdac426de18, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:50,403 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:50,404 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:50,405 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2841310-31ce-4288-a476-8ffb4d427949, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:50,406 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2841310-31ce-4288-a476-8ffb4d427949, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:50,407 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2841310-31ce-4288-a476-8ffb4d427949, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:51,147 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:51,148 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-01 16:19:51,149 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:51,149 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7290d089-181f-4e0d-a189-331f2a74d6f0, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:51,150 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7290d089-181f-4e0d-a189-331f2a74d6f0, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:51,151 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7290d089-181f-4e0d-a189-331f2a74d6f0, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:52,248 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:52,249 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:52,249 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 296f7c29-2ccc-46a7-9f35-82f1e637e584, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:52,250 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 296f7c29-2ccc-46a7-9f35-82f1e637e584, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:52,252 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 296f7c29-2ccc-46a7-9f35-82f1e637e584, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:52,498 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:52,499 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:52,500 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93d193fa-4029-4b0f-8b14-ab67da67c01b, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:52,501 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93d193fa-4029-4b0f-8b14-ab67da67c01b, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:52,502 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93d193fa-4029-4b0f-8b14-ab67da67c01b, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:52,914 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:52,914 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:19:52,915 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 870d0a27-c24c-4336-999b-e8f40e853461, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:19:52,916 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 870d0a27-c24c-4336-999b-e8f40e853461, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:52,917 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 870d0a27-c24c-4336-999b-e8f40e853461, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:56,535 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:06,575 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:06,576 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1fefad60-7cde-46ff-9faf-9800b22d6a94, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:20:06,577 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1fefad60-7cde-46ff-9faf-9800b22d6a94, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:06,578 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1fefad60-7cde-46ff-9faf-9800b22d6a94, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:09,139 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:19,193 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:19,194 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed4b3012-2138-463d-9ace-a4f48e2ef9d1, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:20:19,195 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed4b3012-2138-463d-9ace-a4f48e2ef9d1, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:19,196 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed4b3012-2138-463d-9ace-a4f48e2ef9d1, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:19,776 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:29,780 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:29,781 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ceb665b-89d9-41c3-ac83-9d0199631c12, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:20:29,783 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ceb665b-89d9-41c3-ac83-9d0199631c12, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:29,784 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ceb665b-89d9-41c3-ac83-9d0199631c12, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:30,463 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:30,466 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1497984)
2025-11-01 16:20:30,466 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:30,467 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 618b50ed-f38f-46a7-a316-462e0eb07aa9, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:20:30,468 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 618b50ed-f38f-46a7-a316-462e0eb07aa9, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:30,469 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 618b50ed-f38f-46a7-a316-462e0eb07aa9, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:30,725 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:30,726 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:30,726 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e3a5e408-badd-4523-b303-3fd7a0f3faf7, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:20:30,727 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e3a5e408-badd-4523-b303-3fd7a0f3faf7, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:30,729 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e3a5e408-badd-4523-b303-3fd7a0f3faf7, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:31,345 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:41,350 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:41,351 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7e94f2a0-65eb-46ff-a4ae-a362dc53c122, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:20:41,352 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7e94f2a0-65eb-46ff-a4ae-a362dc53c122, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:41,353 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7e94f2a0-65eb-46ff-a4ae-a362dc53c122, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:41,529 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:20:41,530 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:20:41,545 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:20:41,545 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 08d1f87e-71a7-484a-907c-52d13de7a823, localhost:18143): creating call state
2025-11-01 16:20:41,549 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 08d1f87e-71a7-484a-907c-52d13de7a823, localhost:18143): request = { database: "/Root" }
2025-11-01 16:20:41,621 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 08d1f87e-71a7-484a-907c-52d13de7a823, localhost:18143): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 18143 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-01 16:20:41,622 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:18143, location 1, ssl: False>]>
2025-11-01 16:20:41,623 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:18143
2025-11-01 16:20:41,970 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:20:41,974 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1497984)
2025-11-01 16:20:41,975 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_16_compression` 8092160 / 1497984: 5.402033666581218
2025-11-01 16:20:41,977 - INFO - ya.test - pytest_runtest_teardown: Test teardown
