2025-11-19 11:22:52,597 - INFO - ya.test.419682  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:22:52,597 - INFO - ya.test.419682  - pytest_runtest_setup: test_all_supported_compression[zstd_13_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 13]
2025-11-19 11:22:52,597 - INFO - ya.test.419682  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:22:52,598 - INFO - ya.test.419682  - pytest_runtest_setup: Test setup
2025-11-19 11:22:52,600 - INFO - ya.test.419682  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_13_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 13])
2025-11-19 11:22:52,601 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:52,601 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f5ecef81-5c7a-499e-9d18-fe190ce1c3e8, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:22:52,602 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f5ecef81-5c7a-499e-9d18-fe190ce1c3e8, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_13_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 13)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:52,603 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f5ecef81-5c7a-499e-9d18-fe190ce1c3e8, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:55,766 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:22:55,767 - DEBUG - ydb.connection.419682  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-19 11:22:55,820 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:22:55,821 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ListEndpoints, 61a4d4a0-246d-44a1-9e73-766a6f1399f5, localhost:10572): creating call state
2025-11-19 11:22:55,821 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ListEndpoints, 61a4d4a0-246d-44a1-9e73-766a6f1399f5, localhost:10572): request = { database: "/Root" }
2025-11-19 11:22:57,463 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ListEndpoints, 61a4d4a0-246d-44a1-9e73-766a6f1399f5, localhost:10572): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 10572 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-19 11:22:57,468 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:10572, location 1, ssl: False>]>
2025-11-19 11:22:57,469 - DEBUG - ydb.connection.419682  - close: Closing channel for endpoint localhost:10572
2025-11-19 11:22:58,209 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:58,210 - INFO - __tests__.alter_compression.419682  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_13_compression created
2025-11-19 11:22:58,210 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:58,211 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 1b32ae3b-9560-495d-a72e-4b66b53e3a83, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:22:58,211 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 1b32ae3b-9560-495d-a72e-4b66b53e3a83, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_13_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:58,213 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 1b32ae3b-9560-495d-a72e-4b66b53e3a83, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:59,632 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:59,633 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:59,633 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, eee376bb-0e69-4b45-8af5-ea6c1b4be4e5, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:22:59,634 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, eee376bb-0e69-4b45-8af5-ea6c1b4be4e5, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:59,643 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, eee376bb-0e69-4b45-8af5-ea6c1b4be4e5, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:05,171 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:05,172 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:05,172 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, b99e7f2e-7930-4f59-b792-f8ed3fe0361e, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:05,173 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, b99e7f2e-7930-4f59-b792-f8ed3fe0361e, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:05,175 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, b99e7f2e-7930-4f59-b792-f8ed3fe0361e, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:06,876 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:06,877 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:06,878 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, c0acde26-0558-4381-bf4a-d38ba9eb1046, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:06,879 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, c0acde26-0558-4381-bf4a-d38ba9eb1046, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:06,880 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, c0acde26-0558-4381-bf4a-d38ba9eb1046, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:09,177 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:09,178 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-11-19 11:23:09,179 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:09,179 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 15aa781b-c8e7-4fb3-944b-f3b35851fe36, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:09,181 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 15aa781b-c8e7-4fb3-944b-f3b35851fe36, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:09,182 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 15aa781b-c8e7-4fb3-944b-f3b35851fe36, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:13,424 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:13,425 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:13,426 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, a4592df1-c7bc-4034-9823-cef0d9c6cc70, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:13,427 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, a4592df1-c7bc-4034-9823-cef0d9c6cc70, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:13,433 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, a4592df1-c7bc-4034-9823-cef0d9c6cc70, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:14,371 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:14,371 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:14,372 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 1439236b-4645-433a-b06a-b97fe77ddff0, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:14,373 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 1439236b-4645-433a-b06a-b97fe77ddff0, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:14,380 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 1439236b-4645-433a-b06a-b97fe77ddff0, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:15,141 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:15,142 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-11-19 11:23:15,143 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:15,144 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 6c2a8c4f-ff9e-4599-84b0-d815db10ea68, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:15,145 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 6c2a8c4f-ff9e-4599-84b0-d815db10ea68, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:15,146 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 6c2a8c4f-ff9e-4599-84b0-d815db10ea68, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:19,500 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:19,501 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:19,501 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, e1ab2b09-b02e-423e-85d4-cb16c7eadf12, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:19,502 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, e1ab2b09-b02e-423e-85d4-cb16c7eadf12, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:19,514 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, e1ab2b09-b02e-423e-85d4-cb16c7eadf12, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:20,381 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:20,382 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:20,383 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 351c4754-a958-4c02-8ff5-71bf14930257, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:20,384 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 351c4754-a958-4c02-8ff5-71bf14930257, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:20,391 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 351c4754-a958-4c02-8ff5-71bf14930257, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:21,256 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:21,257 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-11-19 11:23:21,258 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:21,259 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 2502df8d-b8c5-4f4d-8aa3-dfe8af8008bd, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:21,259 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 2502df8d-b8c5-4f4d-8aa3-dfe8af8008bd, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:21,262 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 2502df8d-b8c5-4f4d-8aa3-dfe8af8008bd, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:25,008 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:25,009 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:25,010 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 563124a3-503a-4481-8c39-a35d94892b93, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:25,011 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 563124a3-503a-4481-8c39-a35d94892b93, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:25,016 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 563124a3-503a-4481-8c39-a35d94892b93, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:26,261 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:26,262 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:26,263 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 219bf5f8-9985-49d2-88cb-c7ca61ab30b3, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:26,264 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 219bf5f8-9985-49d2-88cb-c7ca61ab30b3, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:26,266 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 219bf5f8-9985-49d2-88cb-c7ca61ab30b3, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:27,445 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:27,445 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-11-19 11:23:27,446 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:27,447 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 17dcdde5-ccbb-4bdc-a74c-6e5360b7984f, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:27,447 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 17dcdde5-ccbb-4bdc-a74c-6e5360b7984f, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:27,456 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 17dcdde5-ccbb-4bdc-a74c-6e5360b7984f, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:32,908 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:32,909 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:32,910 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f0dcb969-62c2-4451-ada1-94162e61072d, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:32,911 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f0dcb969-62c2-4451-ada1-94162e61072d, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:32,929 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f0dcb969-62c2-4451-ada1-94162e61072d, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:33,685 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:33,685 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:33,686 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 076794f9-bfd6-4e1e-a86e-6c1144e367be, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:33,687 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 076794f9-bfd6-4e1e-a86e-6c1144e367be, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:33,690 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 076794f9-bfd6-4e1e-a86e-6c1144e367be, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:34,582 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:34,583 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-11-19 11:23:34,583 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:34,584 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 24645e9c-8394-4b6e-be66-dd05a80e29a7, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:34,585 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 24645e9c-8394-4b6e-be66-dd05a80e29a7, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:34,587 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 24645e9c-8394-4b6e-be66-dd05a80e29a7, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:39,737 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:39,738 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:39,739 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, a3ff3e20-57e0-4b2b-8435-cd84bf4f8efa, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:39,740 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, a3ff3e20-57e0-4b2b-8435-cd84bf4f8efa, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:39,741 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, a3ff3e20-57e0-4b2b-8435-cd84bf4f8efa, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:40,626 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:40,627 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:40,628 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 6bd84562-5ba1-400c-b291-b24f04e6feed, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:40,629 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 6bd84562-5ba1-400c-b291-b24f04e6feed, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:40,648 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 6bd84562-5ba1-400c-b291-b24f04e6feed, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:41,321 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:41,322 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-11-19 11:23:41,323 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:41,324 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, fba22eed-1d71-4915-9ca5-6116d89b514c, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:41,325 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, fba22eed-1d71-4915-9ca5-6116d89b514c, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:41,327 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, fba22eed-1d71-4915-9ca5-6116d89b514c, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:47,228 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:47,229 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:47,230 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 1ebc1546-ac66-4e7b-869a-9ab272daf088, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:47,231 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 1ebc1546-ac66-4e7b-869a-9ab272daf088, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:47,234 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 1ebc1546-ac66-4e7b-869a-9ab272daf088, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:47,868 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:47,869 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:47,870 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 4914e57f-b8d4-495a-9a02-a1282db4cdf1, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:47,871 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 4914e57f-b8d4-495a-9a02-a1282db4cdf1, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:47,883 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 4914e57f-b8d4-495a-9a02-a1282db4cdf1, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:49,204 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:49,205 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-11-19 11:23:49,205 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:49,206 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, aa23f3ad-1002-4cc4-a7c4-34236a9269b4, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:49,207 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, aa23f3ad-1002-4cc4-a7c4-34236a9269b4, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:49,209 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, aa23f3ad-1002-4cc4-a7c4-34236a9269b4, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:53,794 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:53,795 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:53,795 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 4c21957f-e2a3-4bcc-b2ce-06968dc561af, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:53,796 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 4c21957f-e2a3-4bcc-b2ce-06968dc561af, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:53,800 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 4c21957f-e2a3-4bcc-b2ce-06968dc561af, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:54,431 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:54,432 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:54,433 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, e60e713b-ea4a-420e-bf94-c9b16490b6d1, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:54,434 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, e60e713b-ea4a-420e-bf94-c9b16490b6d1, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:54,438 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, e60e713b-ea4a-420e-bf94-c9b16490b6d1, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:55,734 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:55,735 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-11-19 11:23:55,735 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:23:55,736 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 92c67c74-cd69-4985-81b4-4db7d23b4a4e, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:23:55,737 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 92c67c74-cd69-4985-81b4-4db7d23b4a4e, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:55,739 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 92c67c74-cd69-4985-81b4-4db7d23b4a4e, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:01,204 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:01,205 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:01,206 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, e65a9242-0fb3-4579-bc4c-709e9562dda2, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:01,207 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, e65a9242-0fb3-4579-bc4c-709e9562dda2, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:01,209 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, e65a9242-0fb3-4579-bc4c-709e9562dda2, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:01,801 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:01,802 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:01,802 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, b331fee4-e6c1-46b7-b8eb-65dacfa733c7, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:01,803 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, b331fee4-e6c1-46b7-b8eb-65dacfa733c7, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:01,806 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, b331fee4-e6c1-46b7-b8eb-65dacfa733c7, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:03,152 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:03,153 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-11-19 11:24:03,153 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:03,154 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f014a4fc-fe21-405a-a9d2-f78a351dc0a1, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:03,155 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f014a4fc-fe21-405a-a9d2-f78a351dc0a1, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_13_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:03,157 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f014a4fc-fe21-405a-a9d2-f78a351dc0a1, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:08,548 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:08,549 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:08,550 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, c4c3b11c-68e7-4747-87bd-b79b99f3e96a, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:08,551 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, c4c3b11c-68e7-4747-87bd-b79b99f3e96a, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:08,553 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, c4c3b11c-68e7-4747-87bd-b79b99f3e96a, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:09,088 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:09,089 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:09,090 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 06bdbc25-ddb9-479a-b68a-0e0af6f41ff9, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:09,091 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 06bdbc25-ddb9-479a-b68a-0e0af6f41ff9, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:09,094 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 06bdbc25-ddb9-479a-b68a-0e0af6f41ff9, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:10,811 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:10,812 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_13_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-11-19 11:24:10,813 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:10,813 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 191eeabd-4081-46d7-93d7-f767dc38b8dd, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:10,814 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 191eeabd-4081-46d7-93d7-f767dc38b8dd, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_13_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:10,816 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 191eeabd-4081-46d7-93d7-f767dc38b8dd, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:12,954 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:12,955 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:12,956 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, e6bf9d54-7f00-4c88-8408-f2be8cb0f1d9, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:12,957 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, e6bf9d54-7f00-4c88-8408-f2be8cb0f1d9, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:12,973 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, e6bf9d54-7f00-4c88-8408-f2be8cb0f1d9, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:13,806 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:13,807 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:13,807 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, c498cefb-e05a-42b9-9da9-661e8b3ab965, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:13,808 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, c498cefb-e05a-42b9-9da9-661e8b3ab965, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:13,813 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, c498cefb-e05a-42b9-9da9-661e8b3ab965, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:14,550 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:14,551 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:14,551 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 1f3f0bda-ecb5-4637-b7b0-dae705acca3b, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:14,552 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 1f3f0bda-ecb5-4637-b7b0-dae705acca3b, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:14,554 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 1f3f0bda-ecb5-4637-b7b0-dae705acca3b, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:16,472 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:24:16,473 - DEBUG - ydb.connection.419682  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-19 11:24:16,512 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:24:16,513 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ListEndpoints, 4350571b-17ca-4e19-94c6-1ac231118be5, localhost:10572): creating call state
2025-11-19 11:24:16,514 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ListEndpoints, 4350571b-17ca-4e19-94c6-1ac231118be5, localhost:10572): request = { database: "/Root" }
2025-11-19 11:24:16,642 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ListEndpoints, 4350571b-17ca-4e19-94c6-1ac231118be5, localhost:10572): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 10572 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-19 11:24:16,644 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:10572, location 1, ssl: False>]>
2025-11-19 11:24:16,645 - DEBUG - ydb.connection.419682  - close: Closing channel for endpoint localhost:10572
2025-11-19 11:24:20,602 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:30,640 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:30,641 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 6abf9f76-a3c0-4ba4-8bff-a2284d37f49d, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:30,642 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 6abf9f76-a3c0-4ba4-8bff-a2284d37f49d, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:30,644 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 6abf9f76-a3c0-4ba4-8bff-a2284d37f49d, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:36,575 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:36,622 - INFO - root.419682  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_13_compression`, volumes `value` (8000000, 1596216)
2025-11-19 11:24:36,623 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:36,624 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, c168dda8-06ee-4687-86ce-f7f8bc092739, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:36,625 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, c168dda8-06ee-4687-86ce-f7f8bc092739, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:36,626 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, c168dda8-06ee-4687-86ce-f7f8bc092739, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:36,977 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:36,978 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:36,979 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 6cb94592-9f39-4d11-b744-65ef7228bfd0, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:36,980 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 6cb94592-9f39-4d11-b744-65ef7228bfd0, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:36,981 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 6cb94592-9f39-4d11-b744-65ef7228bfd0, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:41,538 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:51,584 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:51,585 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 28019a4b-d0b9-4e35-a993-28e23f8b05dd, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:24:51,586 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 28019a4b-d0b9-4e35-a993-28e23f8b05dd, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_13_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:24:51,587 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 28019a4b-d0b9-4e35-a993-28e23f8b05dd, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:57,801 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:24:57,888 - INFO - root.419682  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_13_compression`, volumes `value` (8000000, 1596216)
2025-11-19 11:24:57,888 - INFO - root.419682  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_13_compression` 8092160 / 1596216: 5.069589579355175
2025-11-19 11:24:57,890 - INFO - ya.test.419682  - pytest_runtest_teardown: Test teardown
