2025-12-23 15:30:08,669 - INFO - ya.test.739305  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:30:08,670 - INFO - ya.test.739305  - pytest_runtest_setup: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16]
2025-12-23 15:30:08,670 - INFO - ya.test.739305  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:30:08,671 - INFO - ya.test.739305  - pytest_runtest_setup: Test setup
2025-12-23 15:30:08,673 - INFO - ya.test.739305  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_16_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 16])
2025-12-23 15:30:08,674 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:08,674 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 9fc7c452-2efc-41ff-adb2-d3571e64b694, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:08,676 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 9fc7c452-2efc-41ff-adb2-d3571e64b694, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_16_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 16)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:08,677 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 9fc7c452-2efc-41ff-adb2-d3571e64b694, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:12,224 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:12,224 - INFO - __tests__.alter_compression.739305  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_16_compression created
2025-12-23 15:30:12,225 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:12,225 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 8373e924-6a7e-4c0b-a603-ad63d88cc215, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:12,227 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 8373e924-6a7e-4c0b-a603-ad63d88cc215, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:12,228 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 8373e924-6a7e-4c0b-a603-ad63d88cc215, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:13,200 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:13,204 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:13,205 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, ad506fdb-645d-4355-bd9f-8204a4d32f56, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:13,205 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, ad506fdb-645d-4355-bd9f-8204a4d32f56, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:13,207 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, ad506fdb-645d-4355-bd9f-8204a4d32f56, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:20,056 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:20,057 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:20,058 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, c9b4f17b-70de-4279-b5fa-4817d1bf6885, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:20,059 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, c9b4f17b-70de-4279-b5fa-4817d1bf6885, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:20,060 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, c9b4f17b-70de-4279-b5fa-4817d1bf6885, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:21,448 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:21,449 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:21,450 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 282d6c56-519b-47c2-b2e3-313f3736bf2e, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:21,455 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 282d6c56-519b-47c2-b2e3-313f3736bf2e, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:21,457 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 282d6c56-519b-47c2-b2e3-313f3736bf2e, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:23,606 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:23,610 - INFO - __tests__.alter_compression.739305  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-12-23 15:30:23,614 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:23,618 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, c9ca9249-0356-492b-842b-db83e971480d, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:23,626 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, c9ca9249-0356-492b-842b-db83e971480d, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:23,631 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, c9ca9249-0356-492b-842b-db83e971480d, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:28,896 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:28,897 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:28,902 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, b4fb5619-6b23-4d96-9c48-ac4e89bb07d8, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:28,908 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, b4fb5619-6b23-4d96-9c48-ac4e89bb07d8, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:28,912 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, b4fb5619-6b23-4d96-9c48-ac4e89bb07d8, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:29,176 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:29,177 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:29,177 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, d813126e-6db3-4af6-a19d-c29ca362159b, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:29,178 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, d813126e-6db3-4af6-a19d-c29ca362159b, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:29,180 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, d813126e-6db3-4af6-a19d-c29ca362159b, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:29,806 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:29,807 - INFO - __tests__.alter_compression.739305  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-12-23 15:30:29,807 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:29,808 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, e5d886bd-6400-4a45-baad-fa5d7731dc1d, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:29,809 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, e5d886bd-6400-4a45-baad-fa5d7731dc1d, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:29,811 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, e5d886bd-6400-4a45-baad-fa5d7731dc1d, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:32,909 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.739305  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:30:32,910 - DEBUG - ydb.connection.739305  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:30:32,920 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.739305  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:30:32,920 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ListEndpoints, be0053b5-7c06-4cc7-856d-c05959b4582d, localhost:22889): creating call state
2025-12-23 15:30:32,921 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ListEndpoints, be0053b5-7c06-4cc7-856d-c05959b4582d, localhost:22889): request = { database: "/Root" }
2025-12-23 15:30:32,925 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ListEndpoints, be0053b5-7c06-4cc7-856d-c05959b4582d, localhost:22889): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-hclevy2dda.auto.internal" port: 22889 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-23 15:30:32,927 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.739305  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-hclevy2dda.auto.internal:22889, location 1, ssl: False>]>
2025-12-23 15:30:32,927 - DEBUG - ydb.connection.739305  - close: Closing channel for endpoint localhost:22889
2025-12-23 15:30:34,254 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:34,254 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:34,255 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 94a7bf91-44fa-417c-83c5-63abf287fa96, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:34,256 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 94a7bf91-44fa-417c-83c5-63abf287fa96, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:34,263 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 94a7bf91-44fa-417c-83c5-63abf287fa96, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:34,564 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:34,566 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:34,570 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 14d361a5-7544-4114-ab77-da329caad51d, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:34,574 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 14d361a5-7544-4114-ab77-da329caad51d, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:34,577 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 14d361a5-7544-4114-ab77-da329caad51d, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:35,031 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:35,032 - INFO - __tests__.alter_compression.739305  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-12-23 15:30:35,032 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:35,040 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, c5c385df-b210-455f-99f1-26170fb41e77, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:35,041 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, c5c385df-b210-455f-99f1-26170fb41e77, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:35,047 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, c5c385df-b210-455f-99f1-26170fb41e77, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:38,935 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:38,936 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:38,936 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 145c316a-8225-40bc-8360-3e990e3bd6c7, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:38,941 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 145c316a-8225-40bc-8360-3e990e3bd6c7, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:38,942 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 145c316a-8225-40bc-8360-3e990e3bd6c7, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:39,279 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:39,280 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:39,280 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 3b427ff9-08ec-4ed2-a2f1-27a7196776a9, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:39,281 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 3b427ff9-08ec-4ed2-a2f1-27a7196776a9, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:39,283 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 3b427ff9-08ec-4ed2-a2f1-27a7196776a9, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:39,715 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:39,716 - INFO - __tests__.alter_compression.739305  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-12-23 15:30:39,716 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:39,717 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 67f6478f-b365-4497-ad09-1caa268fc248, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:39,717 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 67f6478f-b365-4497-ad09-1caa268fc248, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:39,719 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 67f6478f-b365-4497-ad09-1caa268fc248, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:43,714 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:43,715 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:43,717 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 41f0ed9e-a321-4aaf-9c59-9d021cfb65dc, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:43,720 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 41f0ed9e-a321-4aaf-9c59-9d021cfb65dc, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:43,722 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 41f0ed9e-a321-4aaf-9c59-9d021cfb65dc, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:44,037 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:44,038 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:44,041 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, ff5d8673-71c4-4f9c-a0fb-9d6914888c39, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:44,056 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, ff5d8673-71c4-4f9c-a0fb-9d6914888c39, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:44,062 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, ff5d8673-71c4-4f9c-a0fb-9d6914888c39, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:44,629 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:44,630 - INFO - __tests__.alter_compression.739305  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-12-23 15:30:44,631 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:44,634 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 479ccdc7-2516-4b7d-8101-682d00a7aefe, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:44,635 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 479ccdc7-2516-4b7d-8101-682d00a7aefe, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:44,637 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 479ccdc7-2516-4b7d-8101-682d00a7aefe, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:48,707 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:48,709 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:48,714 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, ca071d99-ce5f-4ee0-95ff-d882d30b5d23, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:48,715 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, ca071d99-ce5f-4ee0-95ff-d882d30b5d23, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:48,717 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, ca071d99-ce5f-4ee0-95ff-d882d30b5d23, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:49,166 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:49,167 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:49,171 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 04087ba5-c57f-4b31-8a70-0f9b3be26131, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:49,173 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 04087ba5-c57f-4b31-8a70-0f9b3be26131, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:49,174 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 04087ba5-c57f-4b31-8a70-0f9b3be26131, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:49,808 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:49,809 - INFO - __tests__.alter_compression.739305  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-12-23 15:30:49,810 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:49,810 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, f5754b2b-3840-49d2-92c9-2462e43472fc, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:49,811 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, f5754b2b-3840-49d2-92c9-2462e43472fc, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:49,813 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, f5754b2b-3840-49d2-92c9-2462e43472fc, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:53,907 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:53,907 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:53,909 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 9e01bc3b-6890-454e-95cd-36bf508a7d97, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:53,910 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 9e01bc3b-6890-454e-95cd-36bf508a7d97, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:53,912 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 9e01bc3b-6890-454e-95cd-36bf508a7d97, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:54,251 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:54,252 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:54,253 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, b02e63f8-07c7-48ed-ae34-1a91cf2720a3, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:54,254 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, b02e63f8-07c7-48ed-ae34-1a91cf2720a3, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:54,256 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, b02e63f8-07c7-48ed-ae34-1a91cf2720a3, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:54,825 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:54,828 - INFO - __tests__.alter_compression.739305  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-12-23 15:30:54,828 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:54,830 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, e2b9cba7-68e6-4999-9326-8475646c6971, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:54,831 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, e2b9cba7-68e6-4999-9326-8475646c6971, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:54,833 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, e2b9cba7-68e6-4999-9326-8475646c6971, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:58,712 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:58,713 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:58,714 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 39b011db-816b-4f3c-8b85-c782e02001b7, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:58,715 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 39b011db-816b-4f3c-8b85-c782e02001b7, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:58,718 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 39b011db-816b-4f3c-8b85-c782e02001b7, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:59,015 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:59,016 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:59,017 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 4e36b761-a599-4923-89cb-78a469e49658, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:59,019 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 4e36b761-a599-4923-89cb-78a469e49658, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:59,020 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 4e36b761-a599-4923-89cb-78a469e49658, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:30:59,645 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:59,646 - INFO - __tests__.alter_compression.739305  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-12-23 15:30:59,647 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:30:59,648 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 0f800b5f-6694-4747-9a5f-f1cae2cb5cbe, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:30:59,650 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 0f800b5f-6694-4747-9a5f-f1cae2cb5cbe, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:30:59,657 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 0f800b5f-6694-4747-9a5f-f1cae2cb5cbe, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:02,925 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:02,925 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:02,927 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 0897d586-a125-4aa7-b3ae-20521c009e97, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:02,929 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 0897d586-a125-4aa7-b3ae-20521c009e97, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:02,932 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 0897d586-a125-4aa7-b3ae-20521c009e97, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:03,216 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:03,217 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:03,217 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, c120fabc-43cf-46f7-a14a-e9054d925fc8, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:03,219 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, c120fabc-43cf-46f7-a14a-e9054d925fc8, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:03,229 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, c120fabc-43cf-46f7-a14a-e9054d925fc8, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:03,859 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:03,860 - INFO - __tests__.alter_compression.739305  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-12-23 15:31:03,860 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:03,862 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, e291b771-d53c-4c0d-80f7-866ed493b02f, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:03,863 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, e291b771-d53c-4c0d-80f7-866ed493b02f, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_16_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:03,865 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, e291b771-d53c-4c0d-80f7-866ed493b02f, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:07,134 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:07,135 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:07,136 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, c750b422-86b1-4404-b4c8-002928edaeb8, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:07,136 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, c750b422-86b1-4404-b4c8-002928edaeb8, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:07,138 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, c750b422-86b1-4404-b4c8-002928edaeb8, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:07,380 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:07,381 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:07,382 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, bf8e3565-647d-4790-ada4-ceed2b643280, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:07,383 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, bf8e3565-647d-4790-ada4-ceed2b643280, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:07,385 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, bf8e3565-647d-4790-ada4-ceed2b643280, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:08,033 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:08,034 - INFO - __tests__.alter_compression.739305  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_16_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-12-23 15:31:08,034 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:08,035 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 4402d483-16dd-4336-a048-ba7d87f78776, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:08,035 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 4402d483-16dd-4336-a048-ba7d87f78776, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_16_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:08,036 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 4402d483-16dd-4336-a048-ba7d87f78776, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:08,907 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:08,908 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:08,909 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 16a78da0-b569-4446-a953-0d8b7a1e7084, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:08,910 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 16a78da0-b569-4446-a953-0d8b7a1e7084, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:08,911 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 16a78da0-b569-4446-a953-0d8b7a1e7084, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:09,157 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:09,158 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:09,158 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, fc2a055e-1631-4b0b-a9e0-9e8706295c38, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:09,159 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, fc2a055e-1631-4b0b-a9e0-9e8706295c38, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:09,161 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, fc2a055e-1631-4b0b-a9e0-9e8706295c38, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:09,469 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:09,470 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:09,471 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, fc69232b-6111-4612-be79-076c37943ef3, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:09,472 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, fc69232b-6111-4612-be79-076c37943ef3, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:09,474 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, fc69232b-6111-4612-be79-076c37943ef3, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:13,268 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:23,306 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:23,308 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, d4c3659c-53c9-4f63-9314-01833ae9c777, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:23,309 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, d4c3659c-53c9-4f63-9314-01833ae9c777, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:23,311 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, d4c3659c-53c9-4f63-9314-01833ae9c777, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:24,930 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:24,939 - INFO - root.739305  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_16_compression`, volumes `value` (8000000, 1534688)
2025-12-23 15:31:24,940 - DEBUG - ydb.query.pool.739305  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:24,941 - DEBUG - ydb.connection.739305  - _prepare_call: RpcState(ExecuteQuery, 7f431e9d-8d00-4bae-8fcc-951aa1348a27, ghrun-hclevy2dda.auto.internal:22889): creating call state
2025-12-23 15:31:24,942 - DEBUG - ydb.connection.739305  - _log_request: RpcState(ExecuteQuery, 7f431e9d-8d00-4bae-8fcc-951aa1348a27, ghrun-hclevy2dda.auto.internal:22889): request = { session_id: "ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_16_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:31:24,944 - DEBUG - ydb.connection.739305  - _log_response: RpcState(ExecuteQuery, 7f431e9d-8d00-4bae-8fcc-951aa1348a27, ghrun-hclevy2dda.auto.internal:22889): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:31:25,361 - DEBUG - ydb.query.pool.739305  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjQ1ZTVmMWUtOTQwMjAyYjItZGFmYWVkM2MtOGQyNWMxZDQ=
2025-12-23 15:31:26,154 - ERROR - ya.test.739305  - logreport: ydb/tests/olap/column_family/compression/alter_compression.py:194: in test_all_supported_compression
    assert table.get_portion_stat_by_tier()['__DEFAULT']['Rows'] == expected_raw // 8
E   assert 1015503 == (8000000 // 8)
2025-12-23 15:31:26,160 - INFO - ya.test.739305  - pytest_runtest_teardown: Test teardown
