2025-10-15 15:14:41,097 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:14:41,099 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_7_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 7]
2025-10-15 15:14:41,099 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:14:41,100 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:14:41,102 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_7_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 7])
2025-10-15 15:14:41,103 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:41,104 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49abaf0e-9cb8-45b1-8ea4-037129327c77, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:41,105 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49abaf0e-9cb8-45b1-8ea4-037129327c77, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_7_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 7)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:41,109 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49abaf0e-9cb8-45b1-8ea4-037129327c77, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:44,242 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:44,243 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_7_compression created
2025-10-15 15:14:44,243 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:44,244 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c8b6b82-7cea-4b4b-a1b2-3806e26a1418, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:44,245 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c8b6b82-7cea-4b4b-a1b2-3806e26a1418, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_7_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:44,247 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c8b6b82-7cea-4b4b-a1b2-3806e26a1418, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:45,101 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:45,102 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:45,102 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61222351-5e37-41af-aa80-a2d610c19b21, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:45,103 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61222351-5e37-41af-aa80-a2d610c19b21, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:45,134 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61222351-5e37-41af-aa80-a2d610c19b21, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:47,178 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:47,179 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:47,180 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d2eb8997-bfac-4cb2-aeae-f69dfd1e5dd3, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:47,180 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d2eb8997-bfac-4cb2-aeae-f69dfd1e5dd3, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:47,181 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d2eb8997-bfac-4cb2-aeae-f69dfd1e5dd3, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:48,054 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:48,055 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:48,055 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 320cfc3a-3e48-4857-96eb-2c78b06fa1c3, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:48,056 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 320cfc3a-3e48-4857-96eb-2c78b06fa1c3, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:48,057 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 320cfc3a-3e48-4857-96eb-2c78b06fa1c3, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:49,553 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:49,554 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-10-15 15:14:49,555 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:49,555 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0ffb671a-b0c1-4b77-8df8-4b072fe4c5b2, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:49,556 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0ffb671a-b0c1-4b77-8df8-4b072fe4c5b2, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:49,558 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0ffb671a-b0c1-4b77-8df8-4b072fe4c5b2, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:50,741 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:50,742 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:50,743 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e2a45786-aa95-4fc7-b814-5cef21342eee, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:50,744 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e2a45786-aa95-4fc7-b814-5cef21342eee, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:50,746 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e2a45786-aa95-4fc7-b814-5cef21342eee, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:51,046 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:51,047 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:51,047 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 78c1078e-12bd-4e15-a7bd-9586e95c9854, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:51,048 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 78c1078e-12bd-4e15-a7bd-9586e95c9854, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:51,049 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 78c1078e-12bd-4e15-a7bd-9586e95c9854, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:51,484 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:51,485 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-10-15 15:14:51,486 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:51,486 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b9f687f7-3430-4b31-8e1a-9ff994f1f4f3, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:51,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b9f687f7-3430-4b31-8e1a-9ff994f1f4f3, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:51,492 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b9f687f7-3430-4b31-8e1a-9ff994f1f4f3, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:51,717 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:14:51,718 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:14:51,741 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:14:51,742 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 98c9fd71-c88e-47cc-8193-a511893a0954, localhost:22481): creating call state
2025-10-15 15:14:51,742 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 98c9fd71-c88e-47cc-8193-a511893a0954, localhost:22481): request = { database: "/Root" }
2025-10-15 15:14:51,755 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 98c9fd71-c88e-47cc-8193-a511893a0954, localhost:22481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 22481 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-15 15:14:51,756 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:22481, location 1, ssl: False>]>
2025-10-15 15:14:51,757 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:22481
2025-10-15 15:14:52,873 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:52,874 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:52,874 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2f7da72f-745b-402e-880f-46cf78f08c6f, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:52,875 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2f7da72f-745b-402e-880f-46cf78f08c6f, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:52,877 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2f7da72f-745b-402e-880f-46cf78f08c6f, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:53,288 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:53,289 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:53,289 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b31c2fdb-3a7e-47d8-a64d-c9e3fa6645cc, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:53,290 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b31c2fdb-3a7e-47d8-a64d-c9e3fa6645cc, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:53,291 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b31c2fdb-3a7e-47d8-a64d-c9e3fa6645cc, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:53,661 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:53,662 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 187}}, blobs: {'__DEFAULT': {'Portions': 187, 'BlobSize': 957240, 'BlobCount': 384}}
2025-10-15 15:14:53,662 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:53,663 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 458c4e34-e13e-470e-91c4-2b314270466c, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:53,664 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 458c4e34-e13e-470e-91c4-2b314270466c, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:53,666 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 458c4e34-e13e-470e-91c4-2b314270466c, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:54,957 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:54,958 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:54,959 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d8a9a55-5112-4c15-9913-a00879b4df79, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:54,960 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d8a9a55-5112-4c15-9913-a00879b4df79, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:54,961 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d8a9a55-5112-4c15-9913-a00879b4df79, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:55,245 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:55,246 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:55,247 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4c5cf453-ac0e-4ae5-b626-0d3dc98da46a, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:55,248 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4c5cf453-ac0e-4ae5-b626-0d3dc98da46a, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:55,250 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4c5cf453-ac0e-4ae5-b626-0d3dc98da46a, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:55,702 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:55,702 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 249}}, blobs: {'__DEFAULT': {'Portions': 249, 'BlobSize': 1276432, 'BlobCount': 510}}
2025-10-15 15:14:55,703 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:55,704 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ffac3f24-6494-4706-aab5-059311ce4f75, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:55,705 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ffac3f24-6494-4706-aab5-059311ce4f75, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:55,707 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ffac3f24-6494-4706-aab5-059311ce4f75, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:57,150 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:57,151 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:57,152 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb837e28-5fab-40a2-a636-0c6ccfd7050c, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:57,153 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb837e28-5fab-40a2-a636-0c6ccfd7050c, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:57,154 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb837e28-5fab-40a2-a636-0c6ccfd7050c, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:57,388 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:57,389 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:57,390 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b4f33aab-ac40-4282-9f61-a3a3ff45ab38, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:57,391 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b4f33aab-ac40-4282-9f61-a3a3ff45ab38, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:57,393 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b4f33aab-ac40-4282-9f61-a3a3ff45ab38, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:57,837 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:57,838 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 307}}, blobs: {'__DEFAULT': {'Portions': 307, 'BlobSize': 1594400, 'BlobCount': 630}}
2025-10-15 15:14:57,839 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:57,840 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70343589-e035-4003-bca9-b603b818a568, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:57,842 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70343589-e035-4003-bca9-b603b818a568, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:57,844 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70343589-e035-4003-bca9-b603b818a568, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:59,253 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:59,254 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:59,255 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f2db70db-b8dc-488a-b29c-474088903b4b, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:59,256 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f2db70db-b8dc-488a-b29c-474088903b4b, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:59,257 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f2db70db-b8dc-488a-b29c-474088903b4b, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:59,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:59,489 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:59,490 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 03efd9a6-91f1-41bd-8562-7e6ec67e6d1d, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:59,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 03efd9a6-91f1-41bd-8562-7e6ec67e6d1d, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:59,493 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 03efd9a6-91f1-41bd-8562-7e6ec67e6d1d, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:59,865 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:59,866 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 363}}, blobs: {'__DEFAULT': {'Portions': 363, 'BlobSize': 1911888, 'BlobCount': 746}}
2025-10-15 15:14:59,866 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:59,867 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7e97a777-4ce3-4abd-8b28-119d289e30bf, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:59,869 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7e97a777-4ce3-4abd-8b28-119d289e30bf, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:59,872 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7e97a777-4ce3-4abd-8b28-119d289e30bf, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:01,450 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:01,451 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:01,452 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e614a5d2-dda1-475f-8625-879b6ab431ca, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:01,453 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e614a5d2-dda1-475f-8625-879b6ab431ca, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:01,460 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e614a5d2-dda1-475f-8625-879b6ab431ca, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:01,807 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:01,808 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:01,809 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 29bb434e-df21-4cfa-b485-d015bd8d1714, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:01,811 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 29bb434e-df21-4cfa-b485-d015bd8d1714, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:01,817 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 29bb434e-df21-4cfa-b485-d015bd8d1714, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:02,301 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:02,302 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 407}}, blobs: {'__DEFAULT': {'Portions': 407, 'BlobSize': 2226424, 'BlobCount': 842}}
2025-10-15 15:15:02,303 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:02,304 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6722d2ae-c2be-4d73-b654-d1135cc949c2, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:02,306 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6722d2ae-c2be-4d73-b654-d1135cc949c2, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:02,308 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6722d2ae-c2be-4d73-b654-d1135cc949c2, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:03,738 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:03,739 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:03,740 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 469e5434-b13e-438b-96eb-8dd879dae955, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:03,741 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 469e5434-b13e-438b-96eb-8dd879dae955, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:03,743 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 469e5434-b13e-438b-96eb-8dd879dae955, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:03,955 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:03,956 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:03,958 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bdb606a6-c826-47c0-be40-577b6f06596a, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:03,965 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bdb606a6-c826-47c0-be40-577b6f06596a, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:03,967 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bdb606a6-c826-47c0-be40-577b6f06596a, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:04,528 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:04,529 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 459}}, blobs: {'__DEFAULT': {'Portions': 459, 'BlobSize': 2542928, 'BlobCount': 950}}
2025-10-15 15:15:04,530 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:04,531 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bc1bafd3-b222-41bc-8804-52c0ef405d72, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:04,532 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bc1bafd3-b222-41bc-8804-52c0ef405d72, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:04,534 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bc1bafd3-b222-41bc-8804-52c0ef405d72, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:06,485 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:06,486 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:06,487 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d79c2b11-6cf5-467e-98b2-d2fbc59cfd2e, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:06,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d79c2b11-6cf5-467e-98b2-d2fbc59cfd2e, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:06,489 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d79c2b11-6cf5-467e-98b2-d2fbc59cfd2e, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:06,905 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:06,906 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:06,907 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b85d532-0a4a-45a5-9533-a4ed579cb405, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:06,908 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b85d532-0a4a-45a5-9533-a4ed579cb405, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:06,914 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b85d532-0a4a-45a5-9533-a4ed579cb405, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:07,698 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:07,699 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 495}}, blobs: {'__DEFAULT': {'Portions': 495, 'BlobSize': 2854592, 'BlobCount': 1030}}
2025-10-15 15:15:07,700 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:07,700 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b57db310-0954-4549-9064-0d4b53c539e3, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:07,702 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b57db310-0954-4549-9064-0d4b53c539e3, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:07,704 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b57db310-0954-4549-9064-0d4b53c539e3, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:09,622 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:09,623 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:09,624 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed789977-0d7a-4d88-92b3-e5cd0b20548a, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:09,625 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed789977-0d7a-4d88-92b3-e5cd0b20548a, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:09,629 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed789977-0d7a-4d88-92b3-e5cd0b20548a, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:09,904 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:09,905 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:09,906 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 459e9022-0488-44b9-8716-426ec6d215a6, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:09,907 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 459e9022-0488-44b9-8716-426ec6d215a6, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:09,909 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 459e9022-0488-44b9-8716-426ec6d215a6, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:10,422 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:10,423 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 1014097, 'Portions': 536}}, blobs: {'__DEFAULT': {'Portions': 527, 'BlobSize': 3165328, 'BlobCount': 1102}}
2025-10-15 15:15:10,423 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:10,424 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ced41d35-edb5-49d6-8aa8-328d1beed253, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:10,427 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ced41d35-edb5-49d6-8aa8-328d1beed253, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_7_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:10,428 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ced41d35-edb5-49d6-8aa8-328d1beed253, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:11,448 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:11,449 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:11,450 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dc8e2729-ce85-422b-8a80-c534c17c46f8, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:11,451 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dc8e2729-ce85-422b-8a80-c534c17c46f8, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:11,452 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dc8e2729-ce85-422b-8a80-c534c17c46f8, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:11,726 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:11,727 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:11,728 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6f24b627-2852-49ff-a6b3-6449dc2a4504, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:11,728 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6f24b627-2852-49ff-a6b3-6449dc2a4504, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:11,731 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6f24b627-2852-49ff-a6b3-6449dc2a4504, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:12,049 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:12,050 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:12,050 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 34c5fed2-1035-4e46-be76-86225384b290, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:12,051 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 34c5fed2-1035-4e46-be76-86225384b290, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:12,054 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 34c5fed2-1035-4e46-be76-86225384b290, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:16,290 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:26,341 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:26,342 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c40a75b7-8aad-4e31-b3fc-e6ab132e23d6, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:26,343 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c40a75b7-8aad-4e31-b3fc-e6ab132e23d6, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:26,345 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c40a75b7-8aad-4e31-b3fc-e6ab132e23d6, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:29,454 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:29,488 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_7_compression`, volumes `value` (8000000, 1570000)
2025-10-15 15:15:29,489 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:29,490 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 826806db-889e-4c54-9868-6a3574319be8, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:29,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 826806db-889e-4c54-9868-6a3574319be8, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:29,492 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 826806db-889e-4c54-9868-6a3574319be8, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:29,854 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:29,855 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:29,856 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 030f84a9-a5a7-4aaa-ab80-01210bd5eb15, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:29,857 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 030f84a9-a5a7-4aaa-ab80-01210bd5eb15, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:29,860 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 030f84a9-a5a7-4aaa-ab80-01210bd5eb15, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:34,341 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:44,400 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:44,401 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49e56f56-8f6d-4c7c-ae9e-944cbc85762f, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:44,402 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49e56f56-8f6d-4c7c-ae9e-944cbc85762f, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:44,404 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49e56f56-8f6d-4c7c-ae9e-944cbc85762f, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:47,767 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:57,793 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:15:57,794 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7857428-25b6-4aa6-9c22-0807c4b09e61, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:15:57,795 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7857428-25b6-4aa6-9c22-0807c4b09e61, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:57,796 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7857428-25b6-4aa6-9c22-0807c4b09e61, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:59,105 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:16:09,125 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:16:09,126 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f8ad54b2-1ec9-41f5-b6c9-aca0dbe20336, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:16:09,127 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f8ad54b2-1ec9-41f5-b6c9-aca0dbe20336, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:16:09,133 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f8ad54b2-1ec9-41f5-b6c9-aca0dbe20336, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:16:10,289 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:16:10,298 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_7_compression`, volumes `value` (8000000, 1488640)
2025-10-15 15:16:10,299 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_7_compression` 8081504 / 1488640: 5.428783319002579
2025-10-15 15:16:10,301 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-15 15:16:10,302 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 34d2427b-1fef-4cc4-9a8a-293800a1e29c, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:16:10,303 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 34d2427b-1fef-4cc4-9a8a-293800a1e29c, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" }
2025-10-15 15:16:10,308 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 34d2427b-1fef-4cc4-9a8a-293800a1e29c, ghrun-lvxbqnxafi.auto.internal:22481): response = { status: SUCCESS }
2025-10-15 15:16:10,309 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-15 15:16:10,310 - DEBUG - ydb.topic - close: Close topic client
2025-10-15 15:16:10,310 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-15 15:16:10,310 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:22481
2025-10-15 15:16:10,311 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-lvxbqnxafi.auto.internal:22481
2025-10-15 15:16:10,317 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 6c8ee3f1-00f3-416f-b9fe-b5c02bf7adf8, ghrun-lvxbqnxafi.auto.internal:22481): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-10-15 15:16:10,319 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-15 15:16:22,333 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:22481/1
2025-10-15 15:16:22,335 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9627
2025-10-15 15:16:22,335 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2979
2025-10-15 15:16:22,335 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16589
2025-10-15 15:16:22,336 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22481
2025-10-15 15:16:22,336 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13824
