2025-11-21 17:40:01,029 - INFO - ya.test.671694  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:40:01,029 - INFO - ya.test.671694  - pytest_runtest_setup: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10]
2025-11-21 17:40:01,030 - INFO - ya.test.671694  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:40:01,031 - INFO - ya.test.671694  - pytest_runtest_setup: Test setup
2025-11-21 17:40:01,032 - INFO - ya.test.671694  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_10_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 10])
2025-11-21 17:40:01,033 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:01,034 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8527d1e8-2e8a-4228-8990-e2c6dd34f807, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:01,035 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8527d1e8-2e8a-4228-8990-e2c6dd34f807, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_10_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 10)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:01,036 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8527d1e8-2e8a-4228-8990-e2c6dd34f807, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:04,390 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:04,391 - INFO - __tests__.alter_compression.671694  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_10_compression created
2025-11-21 17:40:04,391 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:04,392 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 266eaa9c-3a90-4d1f-bba6-f7f487abf8e6, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:04,392 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 266eaa9c-3a90-4d1f-bba6-f7f487abf8e6, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_10_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:04,398 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 266eaa9c-3a90-4d1f-bba6-f7f487abf8e6, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:05,618 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:05,619 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:05,620 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, e106122e-20f6-4fba-99d1-a8bc5b44bd07, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:05,621 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, e106122e-20f6-4fba-99d1-a8bc5b44bd07, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:05,623 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, e106122e-20f6-4fba-99d1-a8bc5b44bd07, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:07,725 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 17:40:07,726 - DEBUG - ydb.connection.671694  - 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-21 17:40:07,774 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - context_resolve: Resolving endpoints for database /Root
2025-11-21 17:40:07,775 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ListEndpoints, 561c46c5-5d2a-43ee-963e-be6e6d3012e5, localhost:21068): creating call state
2025-11-21 17:40:07,775 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ListEndpoints, 561c46c5-5d2a-43ee-963e-be6e6d3012e5, localhost:21068): request = { database: "/Root" }
2025-11-21 17:40:07,784 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ListEndpoints, 561c46c5-5d2a-43ee-963e-be6e6d3012e5, localhost:21068): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-zme4sewyte.auto.internal" port: 21068 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-21 17:40:07,785 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-zme4sewyte.auto.internal:21068, location 1, ssl: False>]>
2025-11-21 17:40:07,795 - DEBUG - ydb.connection.671694  - close: Closing channel for endpoint localhost:21068
2025-11-21 17:40:08,082 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:08,083 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:08,083 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, e0442072-6246-4b9c-98bd-cc25bf54d850, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:08,084 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, e0442072-6246-4b9c-98bd-cc25bf54d850, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:08,085 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, e0442072-6246-4b9c-98bd-cc25bf54d850, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:09,515 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:09,516 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:09,517 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 314e361b-0a36-4cdd-8a67-98b11c6de496, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:09,518 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 314e361b-0a36-4cdd-8a67-98b11c6de496, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:09,519 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 314e361b-0a36-4cdd-8a67-98b11c6de496, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:11,971 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:11,972 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318048, 'BlobCount': 128}}
2025-11-21 17:40:11,973 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:11,974 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 763b1800-21b5-4cac-b5e5-b11da0b0aefb, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:11,975 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 763b1800-21b5-4cac-b5e5-b11da0b0aefb, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:11,977 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 763b1800-21b5-4cac-b5e5-b11da0b0aefb, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:14,547 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:14,548 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:14,549 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 92ad8df5-e10d-4bd0-b118-19299c8d9e0a, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:14,550 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 92ad8df5-e10d-4bd0-b118-19299c8d9e0a, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:14,551 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 92ad8df5-e10d-4bd0-b118-19299c8d9e0a, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:15,002 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:15,003 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:15,004 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 9bfcf44b-73ac-4c73-86e7-d29d307655df, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:15,005 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 9bfcf44b-73ac-4c73-86e7-d29d307655df, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:15,006 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 9bfcf44b-73ac-4c73-86e7-d29d307655df, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:15,515 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:15,515 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637344, 'BlobCount': 256}}
2025-11-21 17:40:15,516 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:15,517 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 00c1aaca-87d9-4d68-879d-82e2ce2a0684, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:15,518 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 00c1aaca-87d9-4d68-879d-82e2ce2a0684, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:15,520 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 00c1aaca-87d9-4d68-879d-82e2ce2a0684, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:17,882 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:17,883 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:17,883 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 5250ce43-6e08-4aaf-a39b-b43fc4f32f79, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:17,884 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 5250ce43-6e08-4aaf-a39b-b43fc4f32f79, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:17,897 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 5250ce43-6e08-4aaf-a39b-b43fc4f32f79, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:18,470 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:18,471 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:18,472 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, ffa7e2c8-1335-4a1e-aa00-1f445a826788, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:18,473 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, ffa7e2c8-1335-4a1e-aa00-1f445a826788, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:18,474 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, ffa7e2c8-1335-4a1e-aa00-1f445a826788, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:19,115 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:19,116 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956384, 'BlobCount': 384}}
2025-11-21 17:40:19,116 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:19,117 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, b28c3043-2a31-4b26-af11-8c86c5258b70, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:19,118 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, b28c3043-2a31-4b26-af11-8c86c5258b70, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:19,120 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, b28c3043-2a31-4b26-af11-8c86c5258b70, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:21,515 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:21,516 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:21,516 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, d00705e3-96a3-4a81-bd84-4dcf99e10cf0, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:21,517 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, d00705e3-96a3-4a81-bd84-4dcf99e10cf0, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:21,519 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, d00705e3-96a3-4a81-bd84-4dcf99e10cf0, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:21,901 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:21,901 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:21,902 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, d00fec40-919b-47fa-b9ab-10e58b3a013b, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:21,903 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, d00fec40-919b-47fa-b9ab-10e58b3a013b, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:21,907 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, d00fec40-919b-47fa-b9ab-10e58b3a013b, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:22,722 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:22,723 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275744, 'BlobCount': 512}}
2025-11-21 17:40:22,724 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:22,724 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 5b8e9fe1-d9a5-4233-8de2-20bb41a1783a, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:22,726 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 5b8e9fe1-d9a5-4233-8de2-20bb41a1783a, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:22,727 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 5b8e9fe1-d9a5-4233-8de2-20bb41a1783a, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:24,970 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:24,971 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:24,972 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 3a9e440b-ba77-435e-808e-fdc02f382d12, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:24,973 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 3a9e440b-ba77-435e-808e-fdc02f382d12, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:24,979 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 3a9e440b-ba77-435e-808e-fdc02f382d12, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:25,398 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:25,399 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:25,400 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8baa132a-5463-4aec-b139-7653ac81600c, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:25,401 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8baa132a-5463-4aec-b139-7653ac81600c, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:25,407 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8baa132a-5463-4aec-b139-7653ac81600c, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:26,189 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:26,190 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594704, 'BlobCount': 640}}
2025-11-21 17:40:26,191 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:26,191 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 3d9f5b2d-de5b-4bf5-bb3b-53764f849315, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:26,192 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 3d9f5b2d-de5b-4bf5-bb3b-53764f849315, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:26,198 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 3d9f5b2d-de5b-4bf5-bb3b-53764f849315, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:28,186 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:28,187 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:28,187 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 06490d48-821c-41a3-a204-2bff82f425a9, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:28,188 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 06490d48-821c-41a3-a204-2bff82f425a9, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:28,190 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 06490d48-821c-41a3-a204-2bff82f425a9, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:28,562 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:28,562 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:28,563 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 6d24c0d2-4b9a-4578-93bd-1569f79e7059, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:28,564 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 6d24c0d2-4b9a-4578-93bd-1569f79e7059, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:28,565 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 6d24c0d2-4b9a-4578-93bd-1569f79e7059, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:29,450 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:29,451 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913888, 'BlobCount': 768}}
2025-11-21 17:40:29,451 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:29,452 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 5e8ee357-c2a6-45a0-8833-d0cf54647a60, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:29,453 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 5e8ee357-c2a6-45a0-8833-d0cf54647a60, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:29,459 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 5e8ee357-c2a6-45a0-8833-d0cf54647a60, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:31,524 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:31,525 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:31,525 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, d9c7f1c3-083d-4e05-b4ec-dca898c5c78d, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:31,526 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, d9c7f1c3-083d-4e05-b4ec-dca898c5c78d, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:31,528 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, d9c7f1c3-083d-4e05-b4ec-dca898c5c78d, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:31,847 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:31,848 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:31,849 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 3df55091-5a2e-4a6d-adc4-9a6308a7c6c3, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:31,849 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 3df55091-5a2e-4a6d-adc4-9a6308a7c6c3, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:31,853 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 3df55091-5a2e-4a6d-adc4-9a6308a7c6c3, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:32,630 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:32,631 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233024, 'BlobCount': 896}}
2025-11-21 17:40:32,632 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:32,632 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 31b12a3c-d4bc-4d64-a7ab-47543f6491e3, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:32,633 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 31b12a3c-d4bc-4d64-a7ab-47543f6491e3, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:32,635 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 31b12a3c-d4bc-4d64-a7ab-47543f6491e3, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:34,643 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:34,644 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:34,645 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 3bb4ef81-7496-4040-8c9c-766eced2a916, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:34,646 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 3bb4ef81-7496-4040-8c9c-766eced2a916, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:34,648 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 3bb4ef81-7496-4040-8c9c-766eced2a916, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:35,078 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:35,079 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:35,080 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 480c046c-5a52-47c3-96f1-e0d11999e329, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:35,081 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 480c046c-5a52-47c3-96f1-e0d11999e329, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:35,084 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 480c046c-5a52-47c3-96f1-e0d11999e329, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:36,032 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:36,032 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552512, 'BlobCount': 1024}}
2025-11-21 17:40:36,033 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:36,034 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, f8822eaa-2772-454d-bc08-dafa22abd4f7, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:36,035 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, f8822eaa-2772-454d-bc08-dafa22abd4f7, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:36,041 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, f8822eaa-2772-454d-bc08-dafa22abd4f7, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:38,034 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:38,034 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:38,035 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 3ef70762-05dd-490e-b4eb-5c0b26744eac, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:38,036 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 3ef70762-05dd-490e-b4eb-5c0b26744eac, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:38,038 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 3ef70762-05dd-490e-b4eb-5c0b26744eac, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:38,402 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:38,403 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:38,404 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 2d5c5252-4455-49f2-8c71-c07b394712f4, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:38,404 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 2d5c5252-4455-49f2-8c71-c07b394712f4, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:38,406 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 2d5c5252-4455-49f2-8c71-c07b394712f4, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:39,242 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:39,243 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871520, 'BlobCount': 1152}}
2025-11-21 17:40:39,243 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:39,244 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 0d3f02a4-b704-4e7b-9f28-d81a818ac0a4, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:39,245 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 0d3f02a4-b704-4e7b-9f28-d81a818ac0a4, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:39,246 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 0d3f02a4-b704-4e7b-9f28-d81a818ac0a4, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:41,411 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:41,412 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:41,413 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 9f91bc03-da95-4964-997d-5ca979f823d0, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:41,414 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 9f91bc03-da95-4964-997d-5ca979f823d0, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:41,417 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 9f91bc03-da95-4964-997d-5ca979f823d0, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:41,921 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:41,922 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:41,923 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 10f3e405-c71f-4e4e-b09d-a669104f750f, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:41,924 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 10f3e405-c71f-4e4e-b09d-a669104f750f, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:41,928 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 10f3e405-c71f-4e4e-b09d-a669104f750f, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:42,928 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:42,929 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_10_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191024, 'BlobCount': 1280}}
2025-11-21 17:40:42,929 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:42,930 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 24b4c796-bdd4-4142-89d9-0d5f62d04059, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:42,931 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 24b4c796-bdd4-4142-89d9-0d5f62d04059, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_10_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:42,933 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 24b4c796-bdd4-4142-89d9-0d5f62d04059, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:44,563 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:44,564 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:44,565 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8e7bd6e0-dfae-4283-aa59-d03f080fa589, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:44,566 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8e7bd6e0-dfae-4283-aa59-d03f080fa589, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:44,567 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8e7bd6e0-dfae-4283-aa59-d03f080fa589, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:44,988 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:44,989 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:44,989 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 7a1c915f-440e-4db8-b7eb-919886e2424b, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:44,990 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 7a1c915f-440e-4db8-b7eb-919886e2424b, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:44,992 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 7a1c915f-440e-4db8-b7eb-919886e2424b, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:45,533 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:45,534 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:45,534 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, b41d8563-20b6-49d9-9bfe-10535d0b9722, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:45,535 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, b41d8563-20b6-49d9-9bfe-10535d0b9722, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:45,540 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, b41d8563-20b6-49d9-9bfe-10535d0b9722, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:51,626 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:01,703 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:01,704 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 62ff3357-6d70-4640-a7af-0e1f23022519, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:01,706 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 62ff3357-6d70-4640-a7af-0e1f23022519, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:01,708 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 62ff3357-6d70-4640-a7af-0e1f23022519, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:05,625 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:05,715 - INFO - root.671694  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1595512)
2025-11-21 17:41:05,716 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:05,716 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 7a2e5255-158f-4f62-b006-4f71cbf8e168, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:05,717 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 7a2e5255-158f-4f62-b006-4f71cbf8e168, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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_10_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:05,719 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 7a2e5255-158f-4f62-b006-4f71cbf8e168, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:06,182 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:06,183 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:06,184 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, cd514c6c-b334-460d-bc79-1d0392146d27, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:06,185 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, cd514c6c-b334-460d-bc79-1d0392146d27, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:06,187 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, cd514c6c-b334-460d-bc79-1d0392146d27, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:09,887 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:19,933 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:19,935 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 06423644-d12f-41cb-95fd-7f404a1927fa, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:19,936 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 06423644-d12f-41cb-95fd-7f404a1927fa, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:19,940 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 06423644-d12f-41cb-95fd-7f404a1927fa, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:20,737 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:30,744 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:30,745 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8b214bc7-6b52-4d6c-961f-030eb962d69e, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:41:30,746 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8b214bc7-6b52-4d6c-961f-030eb962d69e, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_10_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:41:30,747 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8b214bc7-6b52-4d6c-961f-030eb962d69e, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:41:31,553 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:41:31,557 - INFO - root.671694  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_10_compression`, volumes `value` (8000000, 1465240)
2025-11-21 17:41:31,558 - INFO - root.671694  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_10_compression` 8092160 / 1465240: 5.522753951571073
2025-11-21 17:41:31,560 - INFO - ya.test.671694  - pytest_runtest_teardown: Test teardown
