2025-11-19 11:23:02,461 - INFO - ya.test.425468  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:23:02,461 - INFO - ya.test.425468  - pytest_runtest_setup: test_all_supported_compression[zstd_7_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 7]
2025-11-19 11:23:02,461 - INFO - ya.test.425468  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:23:02,461 - INFO - ya.test.425468  - pytest_runtest_setup: Test setup
2025-11-19 11:23:02,464 - INFO - ya.test.425468  - 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-11-19 11:23:02,469 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:02,470 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 62a30f59-2c9e-452c-90f6-0dc5abd61b37, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:02,475 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 62a30f59-2c9e-452c-90f6-0dc5abd61b37, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:02,476 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 62a30f59-2c9e-452c-90f6-0dc5abd61b37, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:08,884 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:08,884 - INFO - __tests__.alter_compression.425468  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_7_compression created
2025-11-19 11:23:08,886 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:08,887 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, af051437-0120-4992-915f-c20dc53308f7, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:08,887 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, af051437-0120-4992-915f-c20dc53308f7, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:08,903 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, af051437-0120-4992-915f-c20dc53308f7, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:10,584 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:10,585 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:10,585 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, b7c1bacb-abcc-41c5-b622-b6a77e447814, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:10,586 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, b7c1bacb-abcc-41c5-b622-b6a77e447814, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:10,592 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, b7c1bacb-abcc-41c5-b622-b6a77e447814, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:13,316 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:13,317 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:13,318 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, f0d18f6f-9b85-49ac-897c-c00ff961b6af, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:13,318 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, f0d18f6f-9b85-49ac-897c-c00ff961b6af, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:13,321 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, f0d18f6f-9b85-49ac-897c-c00ff961b6af, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:14,904 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:14,905 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:14,906 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 18ac6603-33db-411d-bccd-dceefa1e03fa, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:14,907 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 18ac6603-33db-411d-bccd-dceefa1e03fa, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:14,921 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 18ac6603-33db-411d-bccd-dceefa1e03fa, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:18,202 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:18,203 - INFO - __tests__.alter_compression.425468  - 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-11-19 11:23:18,203 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:18,204 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 693f4c32-90b1-4a4f-9e66-05b59071ecb7, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:18,205 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 693f4c32-90b1-4a4f-9e66-05b59071ecb7, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:18,207 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 693f4c32-90b1-4a4f-9e66-05b59071ecb7, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:21,072 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:21,073 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:21,073 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 959320ce-0d38-4eb2-8d58-c6bb3e0c1d55, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:21,074 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 959320ce-0d38-4eb2-8d58-c6bb3e0c1d55, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:21,075 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 959320ce-0d38-4eb2-8d58-c6bb3e0c1d55, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:22,000 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:22,001 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:22,002 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 8f2c122d-44dd-4afd-9ab9-d9ba512146c2, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:22,003 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 8f2c122d-44dd-4afd-9ab9-d9ba512146c2, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:22,005 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 8f2c122d-44dd-4afd-9ab9-d9ba512146c2, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:22,869 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:22,869 - INFO - __tests__.alter_compression.425468  - 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-11-19 11:23:22,870 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:22,871 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 41a0bb79-0eb7-4a2b-87d4-71ce5ccfcc9e, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:22,875 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 41a0bb79-0eb7-4a2b-87d4-71ce5ccfcc9e, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:22,877 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 41a0bb79-0eb7-4a2b-87d4-71ce5ccfcc9e, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:25,583 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:25,584 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:25,585 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, e19c3edc-402e-4091-b2fc-0bc677686428, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:25,586 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, e19c3edc-402e-4091-b2fc-0bc677686428, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:25,597 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, e19c3edc-402e-4091-b2fc-0bc677686428, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:26,249 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:26,250 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:26,251 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 9cf9e0c5-483b-49e4-a27d-f218793e4125, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:26,253 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 9cf9e0c5-483b-49e4-a27d-f218793e4125, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:26,256 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 9cf9e0c5-483b-49e4-a27d-f218793e4125, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:26,902 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:26,903 - INFO - __tests__.alter_compression.425468  - 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-11-19 11:23:26,904 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:26,904 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 85b9d897-e1f6-4ec5-94d9-d14a781f5831, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:26,905 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 85b9d897-e1f6-4ec5-94d9-d14a781f5831, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:26,907 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 85b9d897-e1f6-4ec5-94d9-d14a781f5831, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:29,317 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:29,318 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:29,318 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 4c9d4548-9f06-442d-9ced-b49c5da774f7, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:29,319 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 4c9d4548-9f06-442d-9ced-b49c5da774f7, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:29,321 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 4c9d4548-9f06-442d-9ced-b49c5da774f7, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:29,950 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:29,951 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:29,952 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, f76f5bef-9d0a-40dd-9a88-352bc2198cef, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:29,953 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, f76f5bef-9d0a-40dd-9a88-352bc2198cef, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:29,956 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, f76f5bef-9d0a-40dd-9a88-352bc2198cef, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:31,785 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:31,786 - INFO - __tests__.alter_compression.425468  - 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-11-19 11:23:31,786 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:31,787 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 78ca2fd4-8ebf-48d4-97fc-a453857dbab8, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:31,793 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 78ca2fd4-8ebf-48d4-97fc-a453857dbab8, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:31,794 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 78ca2fd4-8ebf-48d4-97fc-a453857dbab8, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:34,216 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:34,217 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:34,223 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 0ef02caf-70d2-48a1-a7b3-f53795556631, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:34,225 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 0ef02caf-70d2-48a1-a7b3-f53795556631, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:34,227 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 0ef02caf-70d2-48a1-a7b3-f53795556631, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:34,858 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:34,859 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:34,859 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, d5050e46-9141-4455-8925-3b66f1ff5ba2, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:34,866 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, d5050e46-9141-4455-8925-3b66f1ff5ba2, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:34,868 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, d5050e46-9141-4455-8925-3b66f1ff5ba2, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:36,342 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:36,343 - INFO - __tests__.alter_compression.425468  - 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-11-19 11:23:36,343 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:36,344 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 154c0352-d474-436f-8256-5fef12387786, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:36,345 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 154c0352-d474-436f-8256-5fef12387786, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:36,372 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 154c0352-d474-436f-8256-5fef12387786, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:39,047 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:39,048 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:39,049 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 99841b1e-575d-4073-8179-8e405bef2c17, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:39,050 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 99841b1e-575d-4073-8179-8e405bef2c17, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:39,069 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 99841b1e-575d-4073-8179-8e405bef2c17, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:39,577 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:39,578 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:39,578 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, ee9acbb0-c537-410a-956e-836f6f17d939, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:39,579 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, ee9acbb0-c537-410a-956e-836f6f17d939, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:39,601 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, ee9acbb0-c537-410a-956e-836f6f17d939, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:40,970 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:40,971 - INFO - __tests__.alter_compression.425468  - 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-11-19 11:23:40,972 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:40,972 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 32e23bad-d416-41c1-b1a3-3b9003a51b00, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:40,973 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 32e23bad-d416-41c1-b1a3-3b9003a51b00, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:40,976 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 32e23bad-d416-41c1-b1a3-3b9003a51b00, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:43,496 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:43,497 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:43,498 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 6ab1e764-644f-45e1-8df1-2795bc32fb8a, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:43,498 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 6ab1e764-644f-45e1-8df1-2795bc32fb8a, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:43,506 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 6ab1e764-644f-45e1-8df1-2795bc32fb8a, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:44,455 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:44,456 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:44,457 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 2d9ffe6c-a169-4efa-9024-dc96cc00ebc3, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:44,458 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 2d9ffe6c-a169-4efa-9024-dc96cc00ebc3, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:44,459 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 2d9ffe6c-a169-4efa-9024-dc96cc00ebc3, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:46,175 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:46,175 - INFO - __tests__.alter_compression.425468  - 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-11-19 11:23:46,176 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:46,176 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, a0b4c98c-1273-4655-ac41-fd378abda56d, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:46,177 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, a0b4c98c-1273-4655-ac41-fd378abda56d, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:46,179 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, a0b4c98c-1273-4655-ac41-fd378abda56d, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:48,816 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:48,817 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:48,817 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 7b9c747c-fd7a-448a-8378-d8ecffc9e2e9, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:48,818 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 7b9c747c-fd7a-448a-8378-d8ecffc9e2e9, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:48,819 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 7b9c747c-fd7a-448a-8378-d8ecffc9e2e9, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:49,354 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:49,355 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:49,355 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 48e6f675-6fd5-4aab-9965-aa2543c3f1a7, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:49,356 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 48e6f675-6fd5-4aab-9965-aa2543c3f1a7, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:49,357 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 48e6f675-6fd5-4aab-9965-aa2543c3f1a7, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:50,524 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:50,525 - INFO - __tests__.alter_compression.425468  - 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-11-19 11:23:50,525 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:50,526 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 6100d61d-fbc0-489e-8038-6a559cee9f1c, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:50,527 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 6100d61d-fbc0-489e-8038-6a559cee9f1c, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:50,529 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 6100d61d-fbc0-489e-8038-6a559cee9f1c, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:51,212 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.425468  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:23:51,213 - DEBUG - ydb.connection.425468  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-19 11:23:51,271 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.425468  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:23:51,272 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ListEndpoints, bbb246ae-69ea-461e-8cc8-c93f6cb66927, localhost:12506): creating call state
2025-11-19 11:23:51,272 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ListEndpoints, bbb246ae-69ea-461e-8cc8-c93f6cb66927, localhost:12506): request = { database: "/Root" }
2025-11-19 11:23:51,282 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ListEndpoints, bbb246ae-69ea-461e-8cc8-c93f6cb66927, localhost:12506): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 12506 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-19 11:23:51,284 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.425468  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:12506, location 1, ssl: False>]>
2025-11-19 11:23:51,285 - DEBUG - ydb.connection.425468  - close: Closing channel for endpoint localhost:12506
2025-11-19 11:23:53,073 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:53,074 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:53,075 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 82637761-5858-42bc-916d-0e7ea88d8542, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:53,076 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 82637761-5858-42bc-916d-0e7ea88d8542, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:53,085 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 82637761-5858-42bc-916d-0e7ea88d8542, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:53,596 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:53,597 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:53,598 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 8a92560d-54bb-4f02-8a1b-b658aa45d25c, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:53,599 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 8a92560d-54bb-4f02-8a1b-b658aa45d25c, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:53,603 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 8a92560d-54bb-4f02-8a1b-b658aa45d25c, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:54,820 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:54,821 - INFO - __tests__.alter_compression.425468  - 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-11-19 11:23:54,822 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:54,823 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 9ab54b33-2817-445a-a8c5-0840a67817fa, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:54,824 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 9ab54b33-2817-445a-a8c5-0840a67817fa, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:54,830 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 9ab54b33-2817-445a-a8c5-0840a67817fa, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:57,609 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:57,610 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:57,611 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, fc3eff84-4ce9-4682-a91d-5bee1bf9169a, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:57,612 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, fc3eff84-4ce9-4682-a91d-5bee1bf9169a, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:57,642 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, fc3eff84-4ce9-4682-a91d-5bee1bf9169a, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:58,270 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:58,271 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:58,272 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, e9601637-275c-4228-84b2-a5813dc88c2c, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:58,277 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, e9601637-275c-4228-84b2-a5813dc88c2c, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:58,278 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, e9601637-275c-4228-84b2-a5813dc88c2c, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:59,544 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:59,545 - INFO - __tests__.alter_compression.425468  - 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-11-19 11:23:59,546 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:23:59,547 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 14f25929-3e7f-4800-9b07-05d266348e41, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:23:59,549 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 14f25929-3e7f-4800-9b07-05d266348e41, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:23:59,551 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 14f25929-3e7f-4800-9b07-05d266348e41, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:02,397 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:02,398 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:02,399 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, d4272914-1c78-4c8a-9697-43e1a06b9ce4, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:24:02,404 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, d4272914-1c78-4c8a-9697-43e1a06b9ce4, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:24:02,406 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, d4272914-1c78-4c8a-9697-43e1a06b9ce4, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:03,275 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:03,276 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:03,277 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 92cdf695-2f93-40ac-881c-d4afe96a33db, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:24:03,278 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 92cdf695-2f93-40ac-881c-d4afe96a33db, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:24:03,286 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 92cdf695-2f93-40ac-881c-d4afe96a33db, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:04,133 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:04,134 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:04,135 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, be9696b0-243c-42c4-ad9c-3188666786e5, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:24:04,135 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, be9696b0-243c-42c4-ad9c-3188666786e5, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:24:04,137 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, be9696b0-243c-42c4-ad9c-3188666786e5, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:12,268 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:22,368 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:22,369 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, ce4ee071-37a6-4cdc-9801-bbc13f6d358e, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:24:22,370 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, ce4ee071-37a6-4cdc-9801-bbc13f6d358e, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:24:22,372 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, ce4ee071-37a6-4cdc-9801-bbc13f6d358e, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:23,860 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:33,872 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:33,873 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, f02eef6f-3ea9-4245-9f67-baa3ca6ea6fa, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:24:33,874 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, f02eef6f-3ea9-4245-9f67-baa3ca6ea6fa, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:24:33,886 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, f02eef6f-3ea9-4245-9f67-baa3ca6ea6fa, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:34,884 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:44,896 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:44,897 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 03e6f2cf-dd9b-4a14-aaca-146d33589aea, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:24:44,898 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 03e6f2cf-dd9b-4a14-aaca-146d33589aea, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:24:44,899 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 03e6f2cf-dd9b-4a14-aaca-146d33589aea, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:45,813 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:45,817 - INFO - root.425468  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_7_compression`, volumes `value` (8000000, 1465240)
2025-11-19 11:24:45,818 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:45,818 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 568ebdc9-0780-46e2-a6dd-ff16e3f79510, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:24:45,819 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 568ebdc9-0780-46e2-a6dd-ff16e3f79510, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:24:45,827 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 568ebdc9-0780-46e2-a6dd-ff16e3f79510, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:46,184 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:46,185 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:46,186 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 7ca21df4-b135-4d41-b7b0-6b0ae488101e, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:24:46,187 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 7ca21df4-b135-4d41-b7b0-6b0ae488101e, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:24:46,188 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 7ca21df4-b135-4d41-b7b0-6b0ae488101e, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:47,136 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:57,148 - DEBUG - ydb.query.pool.425468  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:57,149 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(ExecuteQuery, 6f1a292d-1381-4594-a47d-aa39790a529e, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:24:57,150 - DEBUG - ydb.connection.425468  - _log_request: RpcState(ExecuteQuery, 6f1a292d-1381-4594-a47d-aa39790a529e, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" 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 }
2025-11-19 11:24:57,151 - DEBUG - ydb.connection.425468  - _log_response: RpcState(ExecuteQuery, 6f1a292d-1381-4594-a47d-aa39790a529e, ghrun-alsqrntpxi.auto.internal:12506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:24:58,418 - DEBUG - ydb.query.pool.425468  - release: Session returned to queue: ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=
2025-11-19 11:24:58,423 - INFO - root.425468  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_7_compression`, volumes `value` (8000000, 1465240)
2025-11-19 11:24:58,423 - INFO - root.425468  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_7_compression` 8092160 / 1465240: 5.522753951571073
2025-11-19 11:24:58,429 - INFO - ya.test.425468  - pytest_runtest_teardown: Test teardown
2025-11-19 11:24:58,430 - DEBUG - ydb.connection.425468  - _prepare_call: RpcState(DeleteSession, 881cf183-c30a-4cba-8d8d-4655e2993453, ghrun-alsqrntpxi.auto.internal:12506): creating call state
2025-11-19 11:24:58,431 - DEBUG - ydb.connection.425468  - _log_request: RpcState(DeleteSession, 881cf183-c30a-4cba-8d8d-4655e2993453, ghrun-alsqrntpxi.auto.internal:12506): request = { session_id: "ydb://session/3?node_id=1&id=NDI5OGQzMmItYWNjYzQ3ZjMtYjEwYmFiMGMtMWIxNTAwYWM=" }
2025-11-19 11:24:58,448 - DEBUG - ydb.connection.425468  - _log_response: RpcState(DeleteSession, 881cf183-c30a-4cba-8d8d-4655e2993453, ghrun-alsqrntpxi.auto.internal:12506): response = { status: SUCCESS }
2025-11-19 11:24:58,449 - DEBUG - ydb.query.pool.425468  - stop: All session were deleted.
2025-11-19 11:24:58,449 - DEBUG - ydb.topic.425468  - close: Close topic client
2025-11-19 11:24:58,449 - DEBUG - ydb.topic.425468  - close: Topic client was closed
2025-11-19 11:24:58,450 - DEBUG - ydb.connection.425468  - close: Closing channel for endpoint localhost:12506
2025-11-19 11:24:58,452 - DEBUG - ydb.connection.425468  - close: Closing channel for endpoint ghrun-alsqrntpxi.auto.internal:12506
2025-11-19 11:24:58,454 - INFO - ydb.pool.Discovery.425468  - run: Successfully terminated discovery process
2025-11-19 11:25:20,482 - INFO - ydb.tests.library.harness.kikimr_runner.425468  - stop: Stopped node localhost:12506/1
2025-11-19 11:25:20,484 - DEBUG - library.python.filelock.425468  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9900
2025-11-19 11:25:20,485 - DEBUG - library.python.filelock.425468  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29349
2025-11-19 11:25:20,485 - DEBUG - library.python.filelock.425468  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7688
2025-11-19 11:25:20,485 - DEBUG - library.python.filelock.425468  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12506
2025-11-19 11:25:20,486 - DEBUG - library.python.filelock.425468  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5972
