2025-11-11 15:38:11,277 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 15:38:11,277 - INFO - ya.test - pytest_runtest_setup: test_insert_rollback
2025-11-11 15:38:11,277 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 15:38:11,277 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-11 15:38:11,278 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_insert.py::TestInsertStatement, test_name: test_insert_rollback)
2025-11-11 15:38:11,278 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=
2025-11-11 15:38:11,291 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f7ea004d-5381-46bb-90ab-2b37d3417ac2, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:11,292 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f7ea004d-5381-46bb-90ab-2b37d3417ac2, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            CREATE TABLE `/Root/insert/table99205` (\n                id Uint64 NOT NULL,\n                vn Uint64,\n                vs Utf8,\n                PRIMARY KEY(id),\n            )\n            WITH (\n                STORE = COLUMN\n            )\n            " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:11,293 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f7ea004d-5381-46bb-90ab-2b37d3417ac2, ghrun-3ynj4chmly.auto.internal:3243): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:12,597 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=
2025-11-11 15:38:12,598 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=
2025-11-11 15:38:12,598 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, bb5c2de1-7704-4c40-aa2f-9a830ef622cc, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,599 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, bb5c2de1-7704-4c40-aa2f-9a830ef622cc, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" tx_settings { serializable_read_write { } } }
2025-11-11 15:38:12,612 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, bb5c2de1-7704-4c40-aa2f-9a830ef622cc, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS tx_meta { id: "01k9ss2j81a28zrhc3t2jtzfrx" } }
2025-11-11 15:38:12,613 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 43ccc900-9e95-49a9-8788-8bb20076a67a, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,613 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 43ccc900-9e95-49a9-8788-8bb20076a67a, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9ss2j81a28zrhc3t2jtzfrx" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table99205` (id, vn, vs) VALUES (0, 0, \'0\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:12,614 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 43ccc900-9e95-49a9-8788-8bb20076a67a, ghrun-3ynj4chmly.auto.internal:3243): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:12,646 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 6fb723ae-f12e-4a3f-b476-f1466d51496b, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,646 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 6fb723ae-f12e-4a3f-b476-f1466d51496b, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" tx_id: "01k9ss2j81a28zrhc3t2jtzfrx" }
2025-11-11 15:38:12,648 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 6fb723ae-f12e-4a3f-b476-f1466d51496b, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS }
2025-11-11 15:38:12,648 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 3e2aba00-d5ef-4e90-a990-645e85729f12, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,649 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 3e2aba00-d5ef-4e90-a990-645e85729f12, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" tx_settings { serializable_read_write { } } }
2025-11-11 15:38:12,651 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 3e2aba00-d5ef-4e90-a990-645e85729f12, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS tx_meta { id: "01k9ss2j9ackcesnh7stkkkfnx" } }
2025-11-11 15:38:12,651 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17481116-4fc3-45b8-877e-707d49837b3d, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,652 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17481116-4fc3-45b8-877e-707d49837b3d, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9ss2j9ackcesnh7stkkkfnx" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table99205` (id, vn, vs) VALUES (1, 1, \'1\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:12,652 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17481116-4fc3-45b8-877e-707d49837b3d, ghrun-3ynj4chmly.auto.internal:3243): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:12,686 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 0fbc3f87-1af6-4e12-8439-ae57d7b8fbc0, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,686 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 0fbc3f87-1af6-4e12-8439-ae57d7b8fbc0, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" tx_id: "01k9ss2j9ackcesnh7stkkkfnx" }
2025-11-11 15:38:12,688 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 0fbc3f87-1af6-4e12-8439-ae57d7b8fbc0, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS }
2025-11-11 15:38:12,689 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, c1c6bd73-1b41-4b5c-8137-ed189c283944, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,689 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, c1c6bd73-1b41-4b5c-8137-ed189c283944, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" tx_settings { serializable_read_write { } } }
2025-11-11 15:38:12,691 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, c1c6bd73-1b41-4b5c-8137-ed189c283944, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS tx_meta { id: "01k9ss2jaj2jb6jd9fgpge0tsv" } }
2025-11-11 15:38:12,691 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 638bc232-db37-4e14-8e55-b25585523676, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,692 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 638bc232-db37-4e14-8e55-b25585523676, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9ss2jaj2jb6jd9fgpge0tsv" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table99205` (id, vn, vs) VALUES (2, 2, \'2\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:12,692 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 638bc232-db37-4e14-8e55-b25585523676, ghrun-3ynj4chmly.auto.internal:3243): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:12,725 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, e07716f9-cca0-45d8-8896-a832ce31b434, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,726 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, e07716f9-cca0-45d8-8896-a832ce31b434, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" tx_id: "01k9ss2jaj2jb6jd9fgpge0tsv" }
2025-11-11 15:38:12,736 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, e07716f9-cca0-45d8-8896-a832ce31b434, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS }
2025-11-11 15:38:12,737 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, e616b29d-4912-451a-be88-86a967b1a993, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,738 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, e616b29d-4912-451a-be88-86a967b1a993, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" tx_settings { serializable_read_write { } } }
2025-11-11 15:38:12,740 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, e616b29d-4912-451a-be88-86a967b1a993, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS tx_meta { id: "01k9ss2jc3b870fk0sdtszb6wp" } }
2025-11-11 15:38:12,740 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d4c7ce2-bd2d-41cc-83bc-ccf8c529aea0, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,741 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d4c7ce2-bd2d-41cc-83bc-ccf8c529aea0, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9ss2jc3b870fk0sdtszb6wp" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table99205` (id, vn, vs) VALUES (3, 3, \'3\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:12,742 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d4c7ce2-bd2d-41cc-83bc-ccf8c529aea0, ghrun-3ynj4chmly.auto.internal:3243): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:12,779 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, f80066b2-4ddd-4135-aeb9-de446a86d5bc, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,780 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, f80066b2-4ddd-4135-aeb9-de446a86d5bc, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" tx_id: "01k9ss2jc3b870fk0sdtszb6wp" }
2025-11-11 15:38:12,782 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, f80066b2-4ddd-4135-aeb9-de446a86d5bc, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS }
2025-11-11 15:38:12,783 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, b2eecd9e-0ec2-43e2-91c6-6719af7afd6b, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,783 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, b2eecd9e-0ec2-43e2-91c6-6719af7afd6b, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" tx_settings { serializable_read_write { } } }
2025-11-11 15:38:12,786 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, b2eecd9e-0ec2-43e2-91c6-6719af7afd6b, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS tx_meta { id: "01k9ss2jdhe4hagfmpwe1dkj0q" } }
2025-11-11 15:38:12,786 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9e5d5bb6-4bcd-4c91-85ae-ab8dfc01dc77, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,810 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9e5d5bb6-4bcd-4c91-85ae-ab8dfc01dc77, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9ss2jdhe4hagfmpwe1dkj0q" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table99205` (id, vn, vs) VALUES (4, 4, \'4\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:12,821 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9e5d5bb6-4bcd-4c91-85ae-ab8dfc01dc77, ghrun-3ynj4chmly.auto.internal:3243): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:12,875 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 2cf423fc-3680-4310-a2f1-841ff58ec6f5, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,876 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 2cf423fc-3680-4310-a2f1-841ff58ec6f5, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=" tx_id: "01k9ss2jdhe4hagfmpwe1dkj0q" }
2025-11-11 15:38:12,881 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 2cf423fc-3680-4310-a2f1-841ff58ec6f5, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS }
2025-11-11 15:38:12,882 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-11 15:38:12,882 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, fada443a-ba3f-4103-8f61-9b9322842506, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,883 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, fada443a-ba3f-4103-8f61-9b9322842506, ghrun-3ynj4chmly.auto.internal:3243): request = {  }
2025-11-11 15:38:12,891 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, fada443a-ba3f-4103-8f61-9b9322842506, ghrun-3ynj4chmly.auto.internal:3243): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YzdjZTU5ZTEtNjRlNDNkM2QtOTYzOWQzNjMtNTkzOWRkMjE=" node_id: 1 }
2025-11-11 15:38:12,892 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, dade0c60-187b-41f2-bff3-759bd8fdb230, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,892 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, dade0c60-187b-41f2-bff3-759bd8fdb230, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YzdjZTU5ZTEtNjRlNDNkM2QtOTYzOWQzNjMtNTkzOWRkMjE=" }
2025-11-11 15:38:12,893 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, dade0c60-187b-41f2-bff3-759bd8fdb230, ghrun-3ynj4chmly.auto.internal:3243): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:12,916 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YzdjZTU5ZTEtNjRlNDNkM2QtOTYzOWQzNjMtNTkzOWRkMjE=
2025-11-11 15:38:12,916 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ad241c43-f8bc-4dc7-b8e7-08589afd3756, ghrun-3ynj4chmly.auto.internal:3243): creating call state
2025-11-11 15:38:12,917 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ad241c43-f8bc-4dc7-b8e7-08589afd3756, ghrun-3ynj4chmly.auto.internal:3243): request = { session_id: "ydb://session/3?node_id=1&id=YzdjZTU5ZTEtNjRlNDNkM2QtOTYzOWQzNjMtNTkzOWRkMjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT count(*) AS cnt FROM `/Root/insert/table99205`;" } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:12,917 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ad241c43-f8bc-4dc7-b8e7-08589afd3756, ghrun-3ynj4chmly.auto.internal:3243): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:13,116 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzdjZTU5ZTEtNjRlNDNkM2QtOTYzOWQzNjMtNTkzOWRkMjE=
2025-11-11 15:38:13,116 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNiNzkxNDktMzI4Y2U1MzAtMjkzYjZmMWMtNGQzYjNjMjg=
2025-11-11 15:38:13,117 - INFO - ya.test - pytest_runtest_teardown: Test teardown
