2025-12-04 21:05:59,700 - INFO - ya.test.704593  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:05:59,704 - INFO - ya.test.704593  - pytest_runtest_setup: test_all_supported_compression[zstd_7_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 7]
2025-12-04 21:05:59,708 - INFO - ya.test.704593  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:05:59,712 - INFO - ya.test.704593  - pytest_runtest_setup: Test setup
2025-12-04 21:05:59,714 - INFO - ya.test.704593  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_7_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 7])
2025-12-04 21:05:59,716 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:05:59,721 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, db4127c5-d8e3-40b5-a029-324a5443963a, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:05:59,722 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, db4127c5-d8e3-40b5-a029-324a5443963a, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_7_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 7)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:05:59,724 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, db4127c5-d8e3-40b5-a029-324a5443963a, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:05,541 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:05,542 - INFO - __tests__.alter_compression.704593  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_7_compression created
2025-12-04 21:06:05,542 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:05,543 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 235b7bc9-34e7-4665-aac8-be875152e3f8, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:05,551 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 235b7bc9-34e7-4665-aac8-be875152e3f8, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_7_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:05,562 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 235b7bc9-34e7-4665-aac8-be875152e3f8, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:07,245 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:07,246 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:07,247 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 064244af-fa15-48a3-8654-4d613e3c2e35, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:07,248 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 064244af-fa15-48a3-8654-4d613e3c2e35, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:07,252 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 064244af-fa15-48a3-8654-4d613e3c2e35, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:09,550 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:09,551 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:09,552 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, f736ff1e-aa85-43a1-91c4-2963f1ec34bb, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:09,553 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, f736ff1e-aa85-43a1-91c4-2963f1ec34bb, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:09,554 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, f736ff1e-aa85-43a1-91c4-2963f1ec34bb, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:10,857 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:10,857 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:10,858 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 96c7f846-bad4-4f51-af0a-3c77853e389d, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:10,865 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 96c7f846-bad4-4f51-af0a-3c77853e389d, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:10,882 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 96c7f846-bad4-4f51-af0a-3c77853e389d, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:12,184 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.704593  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:06:12,184 - DEBUG - ydb.connection.704593  - 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-12-04 21:06:12,208 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.704593  - context_resolve: Resolving endpoints for database /Root
2025-12-04 21:06:12,209 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ListEndpoints, c1ff17d6-ed96-4dc9-869a-f37697ce246b, localhost:16860): creating call state
2025-12-04 21:06:12,210 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ListEndpoints, c1ff17d6-ed96-4dc9-869a-f37697ce246b, localhost:16860): request = { database: "/Root" }
2025-12-04 21:06:12,226 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ListEndpoints, c1ff17d6-ed96-4dc9-869a-f37697ce246b, localhost:16860): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-fryxcz52gi.auto.internal" port: 16860 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-04 21:06:12,227 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.704593  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-fryxcz52gi.auto.internal:16860, location 1, ssl: False>]>
2025-12-04 21:06:12,228 - DEBUG - ydb.connection.704593  - close: Closing channel for endpoint localhost:16860
2025-12-04 21:06:13,173 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:13,173 - INFO - __tests__.alter_compression.704593  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-12-04 21:06:13,182 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:13,189 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, aa39a39f-f5f8-41dd-bcfa-00179d56aa16, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:13,192 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, aa39a39f-f5f8-41dd-bcfa-00179d56aa16, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:13,195 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, aa39a39f-f5f8-41dd-bcfa-00179d56aa16, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:14,936 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:14,937 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:14,938 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 0523d280-f363-4e62-9151-51bc0e74e0a4, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:14,939 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 0523d280-f363-4e62-9151-51bc0e74e0a4, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:14,941 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 0523d280-f363-4e62-9151-51bc0e74e0a4, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:15,306 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:15,307 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:15,309 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 260c58b1-3332-432d-8aaf-3508444a3391, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:15,310 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 260c58b1-3332-432d-8aaf-3508444a3391, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:15,311 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 260c58b1-3332-432d-8aaf-3508444a3391, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:15,796 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:15,801 - INFO - __tests__.alter_compression.704593  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-12-04 21:06:15,808 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:15,812 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 4fc32071-bc1c-44db-a69d-c402499b6325, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:15,814 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 4fc32071-bc1c-44db-a69d-c402499b6325, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:15,822 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 4fc32071-bc1c-44db-a69d-c402499b6325, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:17,364 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:17,364 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:17,365 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 03e8b6ae-e20d-44e6-89f6-6937064defe9, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:17,367 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 03e8b6ae-e20d-44e6-89f6-6937064defe9, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:17,368 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 03e8b6ae-e20d-44e6-89f6-6937064defe9, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:17,709 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:17,710 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:17,711 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 4d940489-a303-436f-b262-8594176ce213, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:17,712 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 4d940489-a303-436f-b262-8594176ce213, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:17,713 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 4d940489-a303-436f-b262-8594176ce213, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:18,101 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:18,108 - INFO - __tests__.alter_compression.704593  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956352, 'BlobCount': 384}}
2025-12-04 21:06:18,108 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:18,109 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, ac06431f-4587-44f5-83ea-cb20dd2e0a6c, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:18,117 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, ac06431f-4587-44f5-83ea-cb20dd2e0a6c, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:18,118 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, ac06431f-4587-44f5-83ea-cb20dd2e0a6c, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:19,597 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:19,601 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:19,603 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, cda79579-9bab-4cab-be97-203cca22e2e6, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:19,609 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, cda79579-9bab-4cab-be97-203cca22e2e6, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:19,610 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, cda79579-9bab-4cab-be97-203cca22e2e6, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:19,885 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:19,889 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:19,892 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, b2d3de19-681e-42d1-b0d3-b4ec01affd65, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:19,893 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, b2d3de19-681e-42d1-b0d3-b4ec01affd65, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:19,894 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, b2d3de19-681e-42d1-b0d3-b4ec01affd65, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:20,481 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:20,484 - INFO - __tests__.alter_compression.704593  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275712, 'BlobCount': 512}}
2025-12-04 21:06:20,489 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:20,494 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 7af19891-f215-4a06-8ca2-629bbebae93a, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:20,506 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 7af19891-f215-4a06-8ca2-629bbebae93a, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:20,524 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 7af19891-f215-4a06-8ca2-629bbebae93a, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:22,026 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:22,030 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:22,031 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 636db2eb-7ac9-4b42-aede-ecf8a744e55e, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:22,032 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 636db2eb-7ac9-4b42-aede-ecf8a744e55e, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:22,045 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 636db2eb-7ac9-4b42-aede-ecf8a744e55e, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:22,360 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:22,360 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:22,367 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, c2832dca-eaf7-4aae-a9b8-ecd7dd85b1ba, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:22,368 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, c2832dca-eaf7-4aae-a9b8-ecd7dd85b1ba, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:22,370 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, c2832dca-eaf7-4aae-a9b8-ecd7dd85b1ba, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:22,890 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:22,890 - INFO - __tests__.alter_compression.704593  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594672, 'BlobCount': 640}}
2025-12-04 21:06:22,891 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:22,892 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, f188218c-f6b1-4512-9dc4-4d43b1cceadd, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:22,893 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, f188218c-f6b1-4512-9dc4-4d43b1cceadd, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:22,895 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, f188218c-f6b1-4512-9dc4-4d43b1cceadd, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:24,414 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:24,419 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:24,420 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, a3da2c2e-6d2e-49bf-82ce-8a8f1b7181c8, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:24,421 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, a3da2c2e-6d2e-49bf-82ce-8a8f1b7181c8, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:24,434 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, a3da2c2e-6d2e-49bf-82ce-8a8f1b7181c8, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:24,812 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:24,813 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:24,820 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, d15fc3b0-59ce-4688-8dc0-755d9136fea9, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:24,822 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, d15fc3b0-59ce-4688-8dc0-755d9136fea9, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:24,839 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, d15fc3b0-59ce-4688-8dc0-755d9136fea9, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:25,500 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:25,501 - INFO - __tests__.alter_compression.704593  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913856, 'BlobCount': 768}}
2025-12-04 21:06:25,504 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:25,505 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 8078f3b3-5b93-45d2-bcdc-aad6ecfc5012, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:25,506 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 8078f3b3-5b93-45d2-bcdc-aad6ecfc5012, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:25,510 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 8078f3b3-5b93-45d2-bcdc-aad6ecfc5012, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:26,924 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:26,925 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:26,926 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 3e692d5e-84d7-4f45-9cd4-10ec23c9f4e5, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:26,932 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 3e692d5e-84d7-4f45-9cd4-10ec23c9f4e5, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:26,941 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 3e692d5e-84d7-4f45-9cd4-10ec23c9f4e5, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:27,201 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:27,202 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:27,203 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 19e188e3-0c28-46fc-9685-c48590d82e49, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:27,204 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 19e188e3-0c28-46fc-9685-c48590d82e49, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:27,206 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 19e188e3-0c28-46fc-9685-c48590d82e49, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:27,696 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:27,697 - INFO - __tests__.alter_compression.704593  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2232992, 'BlobCount': 896}}
2025-12-04 21:06:27,699 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:27,701 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 927dfdb9-0af6-4508-9cc7-7ff2a460b44f, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:27,702 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 927dfdb9-0af6-4508-9cc7-7ff2a460b44f, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:27,703 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 927dfdb9-0af6-4508-9cc7-7ff2a460b44f, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:28,914 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:28,915 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:28,916 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 62cca7b6-48f1-41d3-a168-ace818a96256, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:28,916 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 62cca7b6-48f1-41d3-a168-ace818a96256, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:28,918 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 62cca7b6-48f1-41d3-a168-ace818a96256, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:29,178 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:29,179 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:29,180 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, e4520cf6-784d-48f9-b9b7-e68343c5dccc, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:29,180 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, e4520cf6-784d-48f9-b9b7-e68343c5dccc, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:29,182 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, e4520cf6-784d-48f9-b9b7-e68343c5dccc, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:29,869 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:29,869 - INFO - __tests__.alter_compression.704593  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552480, 'BlobCount': 1024}}
2025-12-04 21:06:29,870 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:29,870 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 47d60e99-423f-4d56-a982-84d6da8b2d90, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:29,871 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 47d60e99-423f-4d56-a982-84d6da8b2d90, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:29,872 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 47d60e99-423f-4d56-a982-84d6da8b2d90, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:31,345 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:31,347 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:31,349 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 19411de4-6892-4b90-98e1-7d650b232b17, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:31,353 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 19411de4-6892-4b90-98e1-7d650b232b17, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:31,355 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 19411de4-6892-4b90-98e1-7d650b232b17, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:31,696 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:31,697 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:31,697 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, add651f9-af5c-4a62-8016-9ac5fddd3605, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:31,699 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, add651f9-af5c-4a62-8016-9ac5fddd3605, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:31,700 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, add651f9-af5c-4a62-8016-9ac5fddd3605, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:32,566 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:32,574 - INFO - __tests__.alter_compression.704593  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871488, 'BlobCount': 1152}}
2025-12-04 21:06:32,575 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:32,582 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, d538022b-f509-4996-a5bc-5681143a010b, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:32,586 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, d538022b-f509-4996-a5bc-5681143a010b, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_7_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:32,588 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, d538022b-f509-4996-a5bc-5681143a010b, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:33,947 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:33,947 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:33,948 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 20fb4e0b-7a4a-4357-8589-863cf9be89a5, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:33,949 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 20fb4e0b-7a4a-4357-8589-863cf9be89a5, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:33,957 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 20fb4e0b-7a4a-4357-8589-863cf9be89a5, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:34,196 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:34,197 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:34,198 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 3df0fada-ec4f-45f8-9f63-d0aaf51759c2, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:34,199 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 3df0fada-ec4f-45f8-9f63-d0aaf51759c2, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:34,200 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 3df0fada-ec4f-45f8-9f63-d0aaf51759c2, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:34,765 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:34,765 - INFO - __tests__.alter_compression.704593  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_7_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3190992, 'BlobCount': 1280}}
2025-12-04 21:06:34,766 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:34,767 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, d7473cc7-3785-4e40-afa4-b8aef0cce8d0, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:34,767 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, d7473cc7-3785-4e40-afa4-b8aef0cce8d0, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_7_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:34,769 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, d7473cc7-3785-4e40-afa4-b8aef0cce8d0, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:35,796 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:35,797 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:35,798 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, f0098ec8-2bd9-45e8-a69e-de4ef1a6f9e9, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:35,800 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, f0098ec8-2bd9-45e8-a69e-de4ef1a6f9e9, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:35,802 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, f0098ec8-2bd9-45e8-a69e-de4ef1a6f9e9, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:36,051 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:36,052 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:36,053 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 13d88ffa-6647-49aa-abae-c387410fa959, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:36,054 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 13d88ffa-6647-49aa-abae-c387410fa959, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:36,055 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 13d88ffa-6647-49aa-abae-c387410fa959, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:36,302 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:36,303 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:36,303 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, a43ba900-2baf-4e02-86ce-280914dc925f, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:36,304 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, a43ba900-2baf-4e02-86ce-280914dc925f, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:36,307 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, a43ba900-2baf-4e02-86ce-280914dc925f, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:39,891 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:49,932 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:49,932 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, b904c82a-75aa-4c09-a8ef-ee45f448432c, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:49,940 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, b904c82a-75aa-4c09-a8ef-ee45f448432c, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:49,945 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, b904c82a-75aa-4c09-a8ef-ee45f448432c, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:53,635 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:53,675 - INFO - root.704593  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_7_compression`, volumes `value` (8000000, 1595496)
2025-12-04 21:06:53,675 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:53,676 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 41e76fec-59e5-4fee-ba45-0b80b27a0ad7, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:53,677 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 41e76fec-59e5-4fee-ba45-0b80b27a0ad7, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:53,678 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 41e76fec-59e5-4fee-ba45-0b80b27a0ad7, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:53,878 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:53,879 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:06:53,880 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, e002656c-1fce-4957-acf0-da15fbca7c42, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:06:53,880 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, e002656c-1fce-4957-acf0-da15fbca7c42, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:06:53,882 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, e002656c-1fce-4957-acf0-da15fbca7c42, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:57,232 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:07:07,268 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:07:07,272 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 30cd2c8c-abd4-4461-9286-6d11355b2729, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:07:07,272 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 30cd2c8c-abd4-4461-9286-6d11355b2729, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:07,273 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 30cd2c8c-abd4-4461-9286-6d11355b2729, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:09,136 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:07:19,153 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:07:19,154 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, f3cfa19e-39d5-435d-a59c-3d0464d221a9, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:07:19,155 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, f3cfa19e-39d5-435d-a59c-3d0464d221a9, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:19,156 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, f3cfa19e-39d5-435d-a59c-3d0464d221a9, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:19,609 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:07:29,613 - DEBUG - ydb.query.pool.704593  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:07:29,613 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(ExecuteQuery, 51f304e8-7a20-417e-b397-3be43fbee9c9, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:07:29,614 - DEBUG - ydb.connection.704593  - _log_request: RpcState(ExecuteQuery, 51f304e8-7a20-417e-b397-3be43fbee9c9, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_7_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:07:29,615 - DEBUG - ydb.connection.704593  - _log_response: RpcState(ExecuteQuery, 51f304e8-7a20-417e-b397-3be43fbee9c9, ghrun-fryxcz52gi.auto.internal:16860): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:07:30,116 - DEBUG - ydb.query.pool.704593  - release: Session returned to queue: ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh
2025-12-04 21:07:30,120 - INFO - root.704593  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_7_compression`, volumes `value` (8000000, 1465240)
2025-12-04 21:07:30,120 - INFO - root.704593  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_7_compression` 8092160 / 1465240: 5.522753951571073
2025-12-04 21:07:30,122 - INFO - ya.test.704593  - pytest_runtest_teardown: Test teardown
2025-12-04 21:07:30,123 - DEBUG - ydb.connection.704593  - _prepare_call: RpcState(DeleteSession, 24688b70-0b3a-4485-8817-f2ea4e9d0d8d, ghrun-fryxcz52gi.auto.internal:16860): creating call state
2025-12-04 21:07:30,123 - DEBUG - ydb.connection.704593  - _log_request: RpcState(DeleteSession, 24688b70-0b3a-4485-8817-f2ea4e9d0d8d, ghrun-fryxcz52gi.auto.internal:16860): request = { session_id: "ydb://session/3?node_id=1&id=NzVhNGRmOS1kYzBmYTJiYy0yNzM5NTk4LTdhNTUxOWZh" }
2025-12-04 21:07:30,126 - DEBUG - ydb.connection.704593  - _log_response: RpcState(DeleteSession, 24688b70-0b3a-4485-8817-f2ea4e9d0d8d, ghrun-fryxcz52gi.auto.internal:16860): response = { status: SUCCESS }
2025-12-04 21:07:30,127 - DEBUG - ydb.query.pool.704593  - stop: All session were deleted.
2025-12-04 21:07:30,128 - DEBUG - ydb.topic.704593  - close: Close topic client
2025-12-04 21:07:30,128 - DEBUG - ydb.connection.704593  - _rpc_error_handler: RpcState(AttachSession, 70aa4e4e-3ccf-4f9f-9ad8-f8c3930a4a05, ghrun-fryxcz52gi.auto.internal:16860): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-04 21:07:30,129 - DEBUG - ydb.topic.704593  - close: Topic client was closed
2025-12-04 21:07:30,131 - DEBUG - ydb.connection.704593  - close: Closing channel for endpoint localhost:16860
2025-12-04 21:07:30,132 - DEBUG - ydb.connection.704593  - close: Closing channel for endpoint ghrun-fryxcz52gi.auto.internal:16860
2025-12-04 21:07:30,133 - INFO - ydb.pool.Discovery.704593  - run: Successfully terminated discovery process
2025-12-04 21:07:42,136 - INFO - ydb.tests.library.harness.kikimr_runner.704593  - stop: Stopped node localhost:16860/1
2025-12-04 21:07:42,138 - DEBUG - library.python.filelock.704593  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23856
2025-12-04 21:07:42,138 - DEBUG - library.python.filelock.704593  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15600
2025-12-04 21:07:42,139 - DEBUG - library.python.filelock.704593  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21948
2025-12-04 21:07:42,139 - DEBUG - library.python.filelock.704593  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16860
2025-12-04 21:07:42,139 - DEBUG - library.python.filelock.704593  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13308
