2025-12-23 15:38:55,048 - INFO - ya.test.668984  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:38:55,048 - INFO - ya.test.668984  - pytest_runtest_setup: test_all_supported_compression[zstd_3_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 3]
2025-12-23 15:38:55,049 - INFO - ya.test.668984  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:38:55,049 - INFO - ya.test.668984  - pytest_runtest_setup: Test setup
2025-12-23 15:38:55,051 - INFO - ya.test.668984  - 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-12-23 15:38:55,052 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:55,052 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 96e7d19f-2c45-4b23-bd29-6c66228fdeff, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:55,053 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 96e7d19f-2c45-4b23-bd29-6c66228fdeff, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:55,054 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 96e7d19f-2c45-4b23-bd29-6c66228fdeff, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:58,503 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:58,504 - INFO - __tests__.alter_compression.668984  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_3_compression created
2025-12-23 15:38:58,505 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:58,505 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 247fddf3-877b-4f21-a448-41ea266f686e, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:58,506 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 247fddf3-877b-4f21-a448-41ea266f686e, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:58,507 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 247fddf3-877b-4f21-a448-41ea266f686e, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:59,259 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:59,260 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:59,261 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 93d4c807-f98a-442d-8665-8aeb8beec94c, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:59,262 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 93d4c807-f98a-442d-8665-8aeb8beec94c, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:59,263 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 93d4c807-f98a-442d-8665-8aeb8beec94c, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:00,778 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:00,778 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:00,779 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 542d7729-1966-42e5-acfe-3e27271ca679, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:00,780 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 542d7729-1966-42e5-acfe-3e27271ca679, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:00,781 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 542d7729-1966-42e5-acfe-3e27271ca679, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:01,825 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:01,826 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:01,826 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 8decce8f-a16a-42e2-9171-3c594dfda03d, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:01,827 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 8decce8f-a16a-42e2-9171-3c594dfda03d, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:01,829 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 8decce8f-a16a-42e2-9171-3c594dfda03d, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:04,057 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:04,057 - INFO - __tests__.alter_compression.668984  - 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-12-23 15:39:04,058 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:04,058 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 0582fcbd-a45d-4792-8cc7-bac45a7abcba, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:04,059 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 0582fcbd-a45d-4792-8cc7-bac45a7abcba, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:04,061 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 0582fcbd-a45d-4792-8cc7-bac45a7abcba, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:05,425 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:05,426 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:05,427 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, e930f142-e46a-494b-8670-caab6e040ca5, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:05,427 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, e930f142-e46a-494b-8670-caab6e040ca5, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:05,433 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, e930f142-e46a-494b-8670-caab6e040ca5, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:05,905 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:05,906 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:05,906 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 8180e841-7506-4207-aedf-6b4db2a2caf9, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:05,907 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 8180e841-7506-4207-aedf-6b4db2a2caf9, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:05,909 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 8180e841-7506-4207-aedf-6b4db2a2caf9, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:06,425 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:06,425 - INFO - __tests__.alter_compression.668984  - 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-12-23 15:39:06,426 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:06,426 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 7abac51a-8154-4049-9019-c360bdac2cf4, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:06,427 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 7abac51a-8154-4049-9019-c360bdac2cf4, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:06,429 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 7abac51a-8154-4049-9019-c360bdac2cf4, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:07,707 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:07,708 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:07,709 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 5acf022a-4d10-4f4c-9126-af9d0ae21925, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:07,709 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 5acf022a-4d10-4f4c-9126-af9d0ae21925, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:07,711 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 5acf022a-4d10-4f4c-9126-af9d0ae21925, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:08,077 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:08,077 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:08,078 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 027ac3f8-c602-4699-a449-ca9897d06f6c, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:08,079 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 027ac3f8-c602-4699-a449-ca9897d06f6c, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:08,081 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 027ac3f8-c602-4699-a449-ca9897d06f6c, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:08,633 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:08,633 - INFO - __tests__.alter_compression.668984  - 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-12-23 15:39:08,634 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:08,634 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 61800e61-487a-4f63-80f1-121675a96bcd, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:08,635 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 61800e61-487a-4f63-80f1-121675a96bcd, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:08,637 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 61800e61-487a-4f63-80f1-121675a96bcd, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:09,759 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:09,760 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:09,760 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3cb1391f-22f7-4200-ba63-90750ec2167c, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:09,761 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3cb1391f-22f7-4200-ba63-90750ec2167c, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:09,763 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3cb1391f-22f7-4200-ba63-90750ec2167c, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:10,134 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:10,135 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:10,136 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, f86d203a-c838-4634-8675-92d086204b6e, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:10,137 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, f86d203a-c838-4634-8675-92d086204b6e, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:10,145 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, f86d203a-c838-4634-8675-92d086204b6e, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:10,669 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:10,670 - INFO - __tests__.alter_compression.668984  - 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-12-23 15:39:10,670 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:10,671 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, dd3f1d99-f02f-4cce-a190-e0be14ab29f8, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:10,672 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, dd3f1d99-f02f-4cce-a190-e0be14ab29f8, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:10,674 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, dd3f1d99-f02f-4cce-a190-e0be14ab29f8, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:12,053 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:12,053 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:12,054 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 166e2430-257d-4bdb-8773-8147da092a82, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:12,055 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 166e2430-257d-4bdb-8773-8147da092a82, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:12,057 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 166e2430-257d-4bdb-8773-8147da092a82, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:12,369 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:12,370 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:12,371 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 7749d05e-fd02-443d-a4f3-1cef579fbda6, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:12,372 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 7749d05e-fd02-443d-a4f3-1cef579fbda6, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:12,374 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 7749d05e-fd02-443d-a4f3-1cef579fbda6, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:12,901 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:12,902 - INFO - __tests__.alter_compression.668984  - 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-12-23 15:39:12,903 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:12,903 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3cb6bfd0-af4e-4ac7-9381-4e4c0b82a7f8, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:12,905 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3cb6bfd0-af4e-4ac7-9381-4e4c0b82a7f8, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:12,906 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3cb6bfd0-af4e-4ac7-9381-4e4c0b82a7f8, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:14,251 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:14,252 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:14,253 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 6530a192-b294-48cc-a3b9-b5ad90592abc, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:14,254 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 6530a192-b294-48cc-a3b9-b5ad90592abc, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:14,255 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 6530a192-b294-48cc-a3b9-b5ad90592abc, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:14,509 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:14,509 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:14,510 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 844f1a5b-d2e3-416c-9d7c-adfa96e3eecb, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:14,511 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 844f1a5b-d2e3-416c-9d7c-adfa96e3eecb, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:14,517 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 844f1a5b-d2e3-416c-9d7c-adfa96e3eecb, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:15,119 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:15,120 - INFO - __tests__.alter_compression.668984  - 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-12-23 15:39:15,120 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:15,121 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 9f8baf58-4f94-4020-8517-92defe14f646, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:15,122 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 9f8baf58-4f94-4020-8517-92defe14f646, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:15,124 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 9f8baf58-4f94-4020-8517-92defe14f646, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:16,209 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:16,209 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:16,210 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3bac48a1-0dbd-452c-b514-97233cad8705, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:16,211 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3bac48a1-0dbd-452c-b514-97233cad8705, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:16,214 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3bac48a1-0dbd-452c-b514-97233cad8705, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:16,492 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:16,493 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:16,494 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, c9a139c7-cfd8-491e-9742-fad79de0e3d4, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:16,495 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, c9a139c7-cfd8-491e-9742-fad79de0e3d4, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:16,498 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, c9a139c7-cfd8-491e-9742-fad79de0e3d4, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:17,153 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:17,153 - INFO - __tests__.alter_compression.668984  - 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-12-23 15:39:17,154 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:17,155 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 9f8f8b65-f7bd-440a-b519-461388432b88, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:17,156 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 9f8f8b65-f7bd-440a-b519-461388432b88, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:17,157 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 9f8f8b65-f7bd-440a-b519-461388432b88, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:18,369 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:18,370 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:18,371 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 387c7078-eae2-4c70-a088-b70a57677fe7, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:18,372 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 387c7078-eae2-4c70-a088-b70a57677fe7, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:18,373 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 387c7078-eae2-4c70-a088-b70a57677fe7, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:18,668 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:18,669 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:18,669 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, a7b6a103-3c1b-4e7a-8730-7069f6194777, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:18,670 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, a7b6a103-3c1b-4e7a-8730-7069f6194777, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:18,671 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, a7b6a103-3c1b-4e7a-8730-7069f6194777, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:19,274 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:19,275 - INFO - __tests__.alter_compression.668984  - 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-12-23 15:39:19,275 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:19,276 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, d32a9a18-7584-4ae4-816b-83398bbffd83, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:19,277 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, d32a9a18-7584-4ae4-816b-83398bbffd83, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:19,279 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, d32a9a18-7584-4ae4-816b-83398bbffd83, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:20,567 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:20,568 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:20,569 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, f5655fd7-f750-4071-8252-1e59496fd835, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:20,569 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, f5655fd7-f750-4071-8252-1e59496fd835, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:20,571 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, f5655fd7-f750-4071-8252-1e59496fd835, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:20,853 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:20,853 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:20,854 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 791ba469-ea68-435d-a8a9-7943a0af1385, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:20,855 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 791ba469-ea68-435d-a8a9-7943a0af1385, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:20,859 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 791ba469-ea68-435d-a8a9-7943a0af1385, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:21,484 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:21,485 - INFO - __tests__.alter_compression.668984  - 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-12-23 15:39:21,485 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:21,486 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 65c6a014-428b-4dcf-9dd8-1c7450710acc, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:21,487 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 65c6a014-428b-4dcf-9dd8-1c7450710acc, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:21,489 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 65c6a014-428b-4dcf-9dd8-1c7450710acc, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:22,672 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:22,673 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:22,674 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 7b57e615-d6dd-4f59-be90-d5b6def38d2a, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:22,675 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 7b57e615-d6dd-4f59-be90-d5b6def38d2a, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:22,676 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 7b57e615-d6dd-4f59-be90-d5b6def38d2a, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:22,981 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:22,981 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:22,982 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 9e16f237-59cd-4213-ae0f-3411905565a6, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:22,983 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 9e16f237-59cd-4213-ae0f-3411905565a6, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:22,984 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 9e16f237-59cd-4213-ae0f-3411905565a6, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:23,651 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:23,652 - INFO - __tests__.alter_compression.668984  - 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-12-23 15:39:23,652 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:23,653 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 899d69d5-77eb-46ce-8e74-c2047faaa214, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:23,654 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 899d69d5-77eb-46ce-8e74-c2047faaa214, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:23,656 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 899d69d5-77eb-46ce-8e74-c2047faaa214, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:24,771 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:24,771 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:24,772 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, de67a736-8924-469d-b0d1-ad55f6d9a846, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:24,773 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, de67a736-8924-469d-b0d1-ad55f6d9a846, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:24,774 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, de67a736-8924-469d-b0d1-ad55f6d9a846, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:25,136 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:25,137 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:25,138 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, ab073e42-df65-44ae-bf40-32e974efbe3a, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:25,138 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, ab073e42-df65-44ae-bf40-32e974efbe3a, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:25,140 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, ab073e42-df65-44ae-bf40-32e974efbe3a, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:25,409 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:25,409 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:25,410 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 12cacdf6-2d07-4a27-8776-cad817f2230b, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:25,411 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 12cacdf6-2d07-4a27-8776-cad817f2230b, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:25,412 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 12cacdf6-2d07-4a27-8776-cad817f2230b, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:29,485 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:39,525 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:39,526 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 1077e6a7-70d8-4dbe-b410-179d53455f8e, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:39,527 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 1077e6a7-70d8-4dbe-b410-179d53455f8e, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:39,528 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 1077e6a7-70d8-4dbe-b410-179d53455f8e, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:42,756 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:42,809 - INFO - root.668984  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_3_compression`, volumes `value` (8000000, 1595968)
2025-12-23 15:39:42,810 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:42,811 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 264164c6-c24f-4ee8-b1a9-9a6907688e4c, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:42,812 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 264164c6-c24f-4ee8-b1a9-9a6907688e4c, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:42,814 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 264164c6-c24f-4ee8-b1a9-9a6907688e4c, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:43,193 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:43,193 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:43,194 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 03b5fd9c-852a-4917-9c82-99ef70fb8cb4, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:43,195 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 03b5fd9c-852a-4917-9c82-99ef70fb8cb4, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:43,196 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 03b5fd9c-852a-4917-9c82-99ef70fb8cb4, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:46,364 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:56,424 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:39:56,425 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 05bae23d-78e6-4fd9-88bf-ba4ea9f93b1f, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:39:56,426 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 05bae23d-78e6-4fd9-88bf-ba4ea9f93b1f, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:56,428 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 05bae23d-78e6-4fd9-88bf-ba4ea9f93b1f, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:40:00,164 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:40:00,206 - INFO - root.668984  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_3_compression`, volumes `value` (8000000, 1595968)
2025-12-23 15:40:00,206 - INFO - root.668984  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_3_compression` 8092160 / 1595968: 5.070377350924329
2025-12-23 15:40:00,209 - INFO - ya.test.668984  - pytest_runtest_teardown: Test teardown
2025-12-23 15:40:00,210 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(DeleteSession, 8056c37d-f122-4b8e-af28-059a9ef55582, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:40:00,210 - DEBUG - ydb.connection.668984  - _log_request: RpcState(DeleteSession, 8056c37d-f122-4b8e-af28-059a9ef55582, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" }
2025-12-23 15:40:00,218 - DEBUG - ydb.connection.668984  - _log_response: RpcState(DeleteSession, 8056c37d-f122-4b8e-af28-059a9ef55582, ghrun-icmf7pzugu.auto.internal:26388): response = { status: SUCCESS }
2025-12-23 15:40:00,220 - DEBUG - ydb.query.pool.668984  - stop: All session were deleted.
2025-12-23 15:40:00,221 - DEBUG - ydb.topic.668984  - close: Close topic client
2025-12-23 15:40:00,221 - DEBUG - ydb.topic.668984  - close: Topic client was closed
2025-12-23 15:40:00,222 - DEBUG - ydb.connection.668984  - close: Closing channel for endpoint localhost:26388
2025-12-23 15:40:00,223 - DEBUG - ydb.connection.668984  - _rpc_error_handler: RpcState(AttachSession, 2d42dd3e-00c9-4a80-99f3-6c7da15f5625, ghrun-icmf7pzugu.auto.internal:26388): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-23 15:40:00,224 - DEBUG - ydb.connection.668984  - close: Closing channel for endpoint ghrun-icmf7pzugu.auto.internal:26388
2025-12-23 15:40:00,226 - INFO - ydb.pool.Discovery.668984  - run: Successfully terminated discovery process
2025-12-23 15:40:17,256 - INFO - ydb.tests.library.harness.kikimr_runner.668984  - stop: Stopped node localhost:26388/1
2025-12-23 15:40:17,260 - DEBUG - library.python.filelock.668984  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10519
2025-12-23 15:40:17,261 - DEBUG - library.python.filelock.668984  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3734
2025-12-23 15:40:17,261 - DEBUG - library.python.filelock.668984  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61028
2025-12-23 15:40:17,262 - DEBUG - library.python.filelock.668984  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26388
2025-12-23 15:40:17,262 - DEBUG - library.python.filelock.668984  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12868
