2025-11-21 20:45:39,784 - INFO - ya.test.669971  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:45:39,785 - INFO - ya.test.669971  - pytest_runtest_setup: test_all_supported_compression[zstd_3_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 3]
2025-11-21 20:45:39,785 - INFO - ya.test.669971  - pytest_runtest_setup: ####################################################################################################
2025-11-21 20:45:39,785 - INFO - ya.test.669971  - pytest_runtest_setup: Test setup
2025-11-21 20:45:39,792 - INFO - ya.test.669971  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_3_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 3])
2025-11-21 20:45:39,792 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:39,793 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 28b53a95-3c18-486e-98c2-58db54e903e0, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:45:39,794 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 28b53a95-3c18-486e-98c2-58db54e903e0, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_3_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 3)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:39,804 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 28b53a95-3c18-486e-98c2-58db54e903e0, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:47,159 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:47,160 - INFO - __tests__.alter_compression.669971  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_3_compression created
2025-11-21 20:45:47,160 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:47,161 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, ad244e95-e9c0-43e6-ad29-d4c069b402da, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:45:47,162 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, ad244e95-e9c0-43e6-ad29-d4c069b402da, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_3_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:47,182 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, ad244e95-e9c0-43e6-ad29-d4c069b402da, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:48,596 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:48,597 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:48,597 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 15b0262d-445c-4287-8b64-8806a109f574, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:45:48,598 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 15b0262d-445c-4287-8b64-8806a109f574, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:48,600 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 15b0262d-445c-4287-8b64-8806a109f574, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:51,047 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:51,048 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:51,048 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 2f76b620-b6eb-4e1c-a161-cffdd21a85c3, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:45:51,049 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 2f76b620-b6eb-4e1c-a161-cffdd21a85c3, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:51,057 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 2f76b620-b6eb-4e1c-a161-cffdd21a85c3, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:53,592 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:53,593 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:53,594 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 3f3aa92a-9d93-460a-90f5-cf8ca4f25ff8, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:45:53,595 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 3f3aa92a-9d93-460a-90f5-cf8ca4f25ff8, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:53,600 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 3f3aa92a-9d93-460a-90f5-cf8ca4f25ff8, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:56,575 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:56,576 - INFO - __tests__.alter_compression.669971  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318960, 'BlobCount': 128}}
2025-11-21 20:45:56,576 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:56,577 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, e8b7fe68-79d7-482e-9d07-6cdff6b293ea, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:45:56,578 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, e8b7fe68-79d7-482e-9d07-6cdff6b293ea, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:56,580 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, e8b7fe68-79d7-482e-9d07-6cdff6b293ea, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:45:59,925 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:59,925 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:45:59,926 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, f0d0c550-5f32-4986-9be7-8286899b64c3, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:45:59,927 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, f0d0c550-5f32-4986-9be7-8286899b64c3, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:45:59,928 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, f0d0c550-5f32-4986-9be7-8286899b64c3, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:00,590 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:00,591 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:00,592 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, e8622872-c405-402f-9737-a76e5cc102f6, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:00,593 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, e8622872-c405-402f-9737-a76e5cc102f6, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:00,607 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, e8622872-c405-402f-9737-a76e5cc102f6, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:01,152 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:01,155 - INFO - __tests__.alter_compression.669971  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638256, 'BlobCount': 256}}
2025-11-21 20:46:01,155 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:01,156 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 545e96fa-b10d-42f9-bfda-2c0d4dbd76d8, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:01,157 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 545e96fa-b10d-42f9-bfda-2c0d4dbd76d8, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:01,159 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 545e96fa-b10d-42f9-bfda-2c0d4dbd76d8, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:02,801 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:02,801 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:02,802 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, c71b10b1-a0d2-4bf6-b65b-f0fe2045af63, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:02,803 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, c71b10b1-a0d2-4bf6-b65b-f0fe2045af63, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:02,808 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, c71b10b1-a0d2-4bf6-b65b-f0fe2045af63, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:03,400 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:03,401 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:03,402 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 09bdc1ba-e472-4e40-91a2-e70a9bd80069, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:03,403 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 09bdc1ba-e472-4e40-91a2-e70a9bd80069, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:03,405 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 09bdc1ba-e472-4e40-91a2-e70a9bd80069, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:04,673 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:04,674 - INFO - __tests__.alter_compression.669971  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957296, 'BlobCount': 384}}
2025-11-21 20:46:04,674 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:04,675 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 771d8b51-6618-455e-aed6-5d15f8e84be3, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:04,676 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 771d8b51-6618-455e-aed6-5d15f8e84be3, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:04,688 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 771d8b51-6618-455e-aed6-5d15f8e84be3, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:07,226 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:07,227 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:07,228 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, b6f1eb23-a478-4416-ae8f-0c6b4e33a533, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:07,229 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, b6f1eb23-a478-4416-ae8f-0c6b4e33a533, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:07,232 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, b6f1eb23-a478-4416-ae8f-0c6b4e33a533, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:07,787 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:07,788 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:07,789 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 0c5767a5-8f38-4588-ae7b-6a95dc86b8e6, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:07,790 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 0c5767a5-8f38-4588-ae7b-6a95dc86b8e6, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:07,794 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 0c5767a5-8f38-4588-ae7b-6a95dc86b8e6, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:09,076 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:09,077 - INFO - __tests__.alter_compression.669971  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276656, 'BlobCount': 512}}
2025-11-21 20:46:09,078 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:09,078 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 80b2ff59-5653-4f13-811d-bef282fcf66f, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:09,083 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 80b2ff59-5653-4f13-811d-bef282fcf66f, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:09,084 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 80b2ff59-5653-4f13-811d-bef282fcf66f, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:09,257 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.669971  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 20:46:09,257 - DEBUG - ydb.connection.669971  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 20:46:09,304 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.669971  - context_resolve: Resolving endpoints for database /Root
2025-11-21 20:46:09,304 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ListEndpoints, 71bc26b4-fe85-46e8-b0a4-603096f457ef, localhost:6158): creating call state
2025-11-21 20:46:09,305 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ListEndpoints, 71bc26b4-fe85-46e8-b0a4-603096f457ef, localhost:6158): request = { database: "/Root" }
2025-11-21 20:46:09,317 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ListEndpoints, 71bc26b4-fe85-46e8-b0a4-603096f457ef, localhost:6158): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-frzglb4yoy.auto.internal" port: 6158 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-21 20:46:09,318 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.669971  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-frzglb4yoy.auto.internal:6158, location 1, ssl: False>]>
2025-11-21 20:46:09,318 - DEBUG - ydb.connection.669971  - close: Closing channel for endpoint localhost:6158
2025-11-21 20:46:11,963 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:11,964 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:11,965 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, d31f703c-b8c2-4530-a920-724aa9cef2d4, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:11,965 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, d31f703c-b8c2-4530-a920-724aa9cef2d4, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:11,978 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, d31f703c-b8c2-4530-a920-724aa9cef2d4, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:12,698 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:12,699 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:12,699 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 5629c858-3d4e-43a6-aefd-30756bd4e13e, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:12,700 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 5629c858-3d4e-43a6-aefd-30756bd4e13e, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:12,704 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 5629c858-3d4e-43a6-aefd-30756bd4e13e, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:13,930 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:13,931 - INFO - __tests__.alter_compression.669971  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595616, 'BlobCount': 640}}
2025-11-21 20:46:13,932 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:13,933 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 67eb3d0d-491d-46dc-b8e7-a7ff3b438677, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:13,934 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 67eb3d0d-491d-46dc-b8e7-a7ff3b438677, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:13,936 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 67eb3d0d-491d-46dc-b8e7-a7ff3b438677, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:15,837 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:15,838 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:15,839 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 9306a32f-6186-4166-a732-bc2bec79e589, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:15,840 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 9306a32f-6186-4166-a732-bc2bec79e589, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:15,850 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 9306a32f-6186-4166-a732-bc2bec79e589, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:16,377 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:16,378 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:16,379 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, d60b8654-8ef6-4c50-b1fc-f3bba34ad151, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:16,380 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, d60b8654-8ef6-4c50-b1fc-f3bba34ad151, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:16,383 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, d60b8654-8ef6-4c50-b1fc-f3bba34ad151, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:17,396 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:17,397 - INFO - __tests__.alter_compression.669971  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914800, 'BlobCount': 768}}
2025-11-21 20:46:17,397 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:17,398 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, f9172ffd-c835-4e20-aea9-6c2ba2b1ecf5, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:17,399 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, f9172ffd-c835-4e20-aea9-6c2ba2b1ecf5, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:17,400 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, f9172ffd-c835-4e20-aea9-6c2ba2b1ecf5, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:19,357 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:19,357 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:19,358 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 51d82961-8f7e-442b-8925-114544fff4bc, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:19,359 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 51d82961-8f7e-442b-8925-114544fff4bc, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:19,365 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 51d82961-8f7e-442b-8925-114544fff4bc, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:20,282 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:20,283 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:20,284 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 0f5146d3-1966-4ff0-9a86-e8e24920cfb5, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:20,285 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 0f5146d3-1966-4ff0-9a86-e8e24920cfb5, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:20,286 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 0f5146d3-1966-4ff0-9a86-e8e24920cfb5, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:21,257 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:21,258 - INFO - __tests__.alter_compression.669971  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233936, 'BlobCount': 896}}
2025-11-21 20:46:21,258 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:21,259 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 21709b7e-2481-489f-a5b8-87afbda0235f, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:21,260 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 21709b7e-2481-489f-a5b8-87afbda0235f, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:21,261 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 21709b7e-2481-489f-a5b8-87afbda0235f, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:22,993 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:22,994 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:22,995 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 293e9fa9-7743-4268-aafa-725fd2a79e01, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:22,995 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 293e9fa9-7743-4268-aafa-725fd2a79e01, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:22,997 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 293e9fa9-7743-4268-aafa-725fd2a79e01, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:23,826 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:23,827 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:23,828 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 136dcf72-4719-4558-865d-fc16eee6ae85, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:23,829 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 136dcf72-4719-4558-865d-fc16eee6ae85, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:23,831 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 136dcf72-4719-4558-865d-fc16eee6ae85, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:25,589 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:25,590 - INFO - __tests__.alter_compression.669971  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553424, 'BlobCount': 1024}}
2025-11-21 20:46:25,590 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:25,591 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 9ad2dc91-77a3-40e5-ba45-ed2c25b161e6, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:25,592 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 9ad2dc91-77a3-40e5-ba45-ed2c25b161e6, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:25,593 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 9ad2dc91-77a3-40e5-ba45-ed2c25b161e6, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:27,483 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:27,483 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:27,484 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, ccc8f7c6-31c8-4528-8c1a-9abf3ac2e308, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:27,485 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, ccc8f7c6-31c8-4528-8c1a-9abf3ac2e308, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:27,486 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, ccc8f7c6-31c8-4528-8c1a-9abf3ac2e308, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:28,320 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:28,321 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:28,322 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 27888323-9cda-4ba0-b75a-7380ede00a4d, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:28,323 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 27888323-9cda-4ba0-b75a-7380ede00a4d, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:28,326 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 27888323-9cda-4ba0-b75a-7380ede00a4d, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:29,408 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:29,409 - INFO - __tests__.alter_compression.669971  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872432, 'BlobCount': 1152}}
2025-11-21 20:46:29,410 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:29,410 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, ac7c6917-a0ef-4c34-b44b-3f42d3eecd1b, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:29,411 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, ac7c6917-a0ef-4c34-b44b-3f42d3eecd1b, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:29,413 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, ac7c6917-a0ef-4c34-b44b-3f42d3eecd1b, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:32,005 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:32,006 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:32,081 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 0a19009f-78dd-4bfd-b5a7-ee6bec8d70d9, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:32,082 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 0a19009f-78dd-4bfd-b5a7-ee6bec8d70d9, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:32,084 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 0a19009f-78dd-4bfd-b5a7-ee6bec8d70d9, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:32,627 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:32,628 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:32,629 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 69309db0-a10a-4c10-af6f-e021e42e805f, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:32,630 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 69309db0-a10a-4c10-af6f-e021e42e805f, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:32,632 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 69309db0-a10a-4c10-af6f-e021e42e805f, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:33,940 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:33,941 - INFO - __tests__.alter_compression.669971  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_3_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191936, 'BlobCount': 1280}}
2025-11-21 20:46:33,942 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:33,942 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 815f608e-3471-4827-b983-46cf4d572daa, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:33,943 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 815f608e-3471-4827-b983-46cf4d572daa, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_3_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:33,945 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 815f608e-3471-4827-b983-46cf4d572daa, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:36,902 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:36,903 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:36,904 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 94b55a72-7867-43f0-a197-59023b917bb4, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:36,905 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 94b55a72-7867-43f0-a197-59023b917bb4, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:36,909 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 94b55a72-7867-43f0-a197-59023b917bb4, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:37,636 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:37,637 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:37,638 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 58832265-1d82-4091-82d1-b7ca1a497a6e, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:37,642 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 58832265-1d82-4091-82d1-b7ca1a497a6e, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:37,644 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 58832265-1d82-4091-82d1-b7ca1a497a6e, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:38,037 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:38,038 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:38,039 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 75e62b21-1209-44fe-8c80-22e3efb584a6, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:38,040 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 75e62b21-1209-44fe-8c80-22e3efb584a6, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:38,042 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 75e62b21-1209-44fe-8c80-22e3efb584a6, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:43,963 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:54,000 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:46:54,000 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 7f869eda-8920-4c17-ad51-d5572e5d5ccc, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:46:54,001 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 7f869eda-8920-4c17-ad51-d5572e5d5ccc, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:46:54,005 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 7f869eda-8920-4c17-ad51-d5572e5d5ccc, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:46:57,804 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:07,952 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:07,953 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, c673917b-db7b-48e4-aeea-07f48428dcc4, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:47:07,954 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, c673917b-db7b-48e4-aeea-07f48428dcc4, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:07,956 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, c673917b-db7b-48e4-aeea-07f48428dcc4, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:09,345 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:19,350 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:19,351 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 7ffe0d03-e5ef-4d5e-ab38-a59affad37f5, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:47:19,352 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 7ffe0d03-e5ef-4d5e-ab38-a59affad37f5, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:19,353 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 7ffe0d03-e5ef-4d5e-ab38-a59affad37f5, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:20,955 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:20,959 - INFO - root.669971  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_3_compression`, volumes `value` (8000000, 1466080)
2025-11-21 20:47:20,960 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:20,960 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, c7651cab-055f-4f4a-95b2-251d79c6ac26, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:47:20,961 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, c7651cab-055f-4f4a-95b2-251d79c6ac26, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" 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_3_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:20,962 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, c7651cab-055f-4f4a-95b2-251d79c6ac26, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:21,496 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:21,497 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:21,498 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 77774d4c-57fc-4c8b-b167-4b7513de9ab1, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:47:21,499 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 77774d4c-57fc-4c8b-b167-4b7513de9ab1, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:21,500 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 77774d4c-57fc-4c8b-b167-4b7513de9ab1, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:22,792 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:26,602 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.669971  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 20:47:26,603 - DEBUG - ydb.connection.669971  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 20:47:26,633 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.669971  - context_resolve: Resolving endpoints for database /Root
2025-11-21 20:47:26,635 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ListEndpoints, ee8f6a4f-2bc6-493c-9057-b8217e22faef, localhost:6158): creating call state
2025-11-21 20:47:26,635 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ListEndpoints, ee8f6a4f-2bc6-493c-9057-b8217e22faef, localhost:6158): request = { database: "/Root" }
2025-11-21 20:47:26,657 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ListEndpoints, ee8f6a4f-2bc6-493c-9057-b8217e22faef, localhost:6158): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-frzglb4yoy.auto.internal" port: 6158 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-21 20:47:26,658 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.669971  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-frzglb4yoy.auto.internal:6158, location 1, ssl: False>]>
2025-11-21 20:47:26,659 - DEBUG - ydb.connection.669971  - close: Closing channel for endpoint localhost:6158
2025-11-21 20:47:32,799 - DEBUG - ydb.query.pool.669971  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:32,800 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(ExecuteQuery, 4cffcb80-9290-401d-995b-694e8d41c60a, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:47:32,801 - DEBUG - ydb.connection.669971  - _log_request: RpcState(ExecuteQuery, 4cffcb80-9290-401d-995b-694e8d41c60a, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_3_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 20:47:32,803 - DEBUG - ydb.connection.669971  - _log_response: RpcState(ExecuteQuery, 4cffcb80-9290-401d-995b-694e8d41c60a, ghrun-frzglb4yoy.auto.internal:6158): response = { <_MultiThreadedRendezvous object> }
2025-11-21 20:47:34,154 - DEBUG - ydb.query.pool.669971  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=
2025-11-21 20:47:34,159 - INFO - root.669971  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_3_compression`, volumes `value` (8000000, 1466080)
2025-11-21 20:47:34,159 - INFO - root.669971  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_3_compression` 8092160 / 1466080: 5.519589654043435
2025-11-21 20:47:34,162 - INFO - ya.test.669971  - pytest_runtest_teardown: Test teardown
2025-11-21 20:47:34,163 - DEBUG - ydb.connection.669971  - _prepare_call: RpcState(DeleteSession, bfb85398-a314-4091-a4ee-d205601882bd, ghrun-frzglb4yoy.auto.internal:6158): creating call state
2025-11-21 20:47:34,164 - DEBUG - ydb.connection.669971  - _log_request: RpcState(DeleteSession, bfb85398-a314-4091-a4ee-d205601882bd, ghrun-frzglb4yoy.auto.internal:6158): request = { session_id: "ydb://session/3?node_id=1&id=ZmM4OTY4Y2YtMmUxNmYzM2QtNTAxMzZiZjctZGVmNjhjMzU=" }
2025-11-21 20:47:34,173 - DEBUG - ydb.connection.669971  - _log_response: RpcState(DeleteSession, bfb85398-a314-4091-a4ee-d205601882bd, ghrun-frzglb4yoy.auto.internal:6158): response = { status: SUCCESS }
2025-11-21 20:47:34,174 - DEBUG - ydb.query.pool.669971  - stop: All session were deleted.
2025-11-21 20:47:34,175 - DEBUG - ydb.topic.669971  - close: Close topic client
2025-11-21 20:47:34,175 - DEBUG - ydb.topic.669971  - close: Topic client was closed
2025-11-21 20:47:34,176 - DEBUG - ydb.connection.669971  - close: Closing channel for endpoint localhost:6158
2025-11-21 20:47:34,179 - DEBUG - ydb.connection.669971  - close: Closing channel for endpoint ghrun-frzglb4yoy.auto.internal:6158
2025-11-21 20:47:34,181 - INFO - ydb.pool.Discovery.669971  - run: Successfully terminated discovery process
2025-11-21 20:47:34,182 - DEBUG - ydb.connection.669971  - _rpc_error_handler: RpcState(AttachSession, e7f94774-669e-4d3a-ba9c-8a88e129c031, ghrun-frzglb4yoy.auto.internal:6158): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-21 20:47:51,219 - INFO - ydb.tests.library.harness.kikimr_runner.669971  - stop: Stopped node localhost:6158/1
2025-11-21 20:47:51,223 - DEBUG - library.python.filelock.669971  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25081
2025-11-21 20:47:51,223 - DEBUG - library.python.filelock.669971  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15673
2025-11-21 20:47:51,224 - DEBUG - library.python.filelock.669971  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18108
2025-11-21 20:47:51,224 - DEBUG - library.python.filelock.669971  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6158
2025-11-21 20:47:51,225 - DEBUG - library.python.filelock.669971  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31540
