2025-11-11 13:03:38,116 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 13:03:38,116 - INFO - ya.test - pytest_runtest_setup: test_insert_rollback
2025-11-11 13:03:38,116 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 13:03:38,116 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-11 13:03:38,117 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_insert.py::TestInsertStatement, test_name: test_insert_rollback)
2025-11-11 13:03:38,117 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl
2025-11-11 13:03:38,118 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a1f4a505-13d0-47d1-a161-4b3da517a3c1, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,118 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a1f4a505-13d0-47d1-a161-4b3da517a3c1, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            CREATE TABLE `/Root/insert/table85978` (\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 13:03:38,119 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a1f4a505-13d0-47d1-a161-4b3da517a3c1, ghrun-3ynj4chmly.auto.internal:22822): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:03:38,485 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl
2025-11-11 13:03:38,485 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl
2025-11-11 13:03:38,486 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 9c5bf8a9-26da-401a-8286-8e4749b4bd5e, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,486 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 9c5bf8a9-26da-401a-8286-8e4749b4bd5e, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" tx_settings { serializable_read_write { } } }
2025-11-11 13:03:38,488 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 9c5bf8a9-26da-401a-8286-8e4749b4bd5e, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS tx_meta { id: "01k9sg7hfqcfmz0nr3qety652w" } }
2025-11-11 13:03:38,489 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e278a721-fa4c-4e50-ba5e-5f91cc3c9024, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,489 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e278a721-fa4c-4e50-ba5e-5f91cc3c9024, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9sg7hfqcfmz0nr3qety652w" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table85978` (id, vn, vs) VALUES (0, 0, \'0\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 13:03:38,490 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e278a721-fa4c-4e50-ba5e-5f91cc3c9024, ghrun-3ynj4chmly.auto.internal:22822): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:03:38,508 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, b8f5e659-f864-4e7f-be73-7f156cc6e3d6, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,509 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, b8f5e659-f864-4e7f-be73-7f156cc6e3d6, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" tx_id: "01k9sg7hfqcfmz0nr3qety652w" }
2025-11-11 13:03:38,511 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, b8f5e659-f864-4e7f-be73-7f156cc6e3d6, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS }
2025-11-11 13:03:38,511 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 4bf1c072-90be-4adc-9a51-b14ddceaa5fd, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,512 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 4bf1c072-90be-4adc-9a51-b14ddceaa5fd, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" tx_settings { serializable_read_write { } } }
2025-11-11 13:03:38,513 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 4bf1c072-90be-4adc-9a51-b14ddceaa5fd, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS tx_meta { id: "01k9sg7hgh2t6g6pr7v4850xr9" } }
2025-11-11 13:03:38,514 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b190e74d-4548-400d-81c0-3114ec6babad, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,514 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b190e74d-4548-400d-81c0-3114ec6babad, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9sg7hgh2t6g6pr7v4850xr9" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table85978` (id, vn, vs) VALUES (1, 1, \'1\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 13:03:38,515 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b190e74d-4548-400d-81c0-3114ec6babad, ghrun-3ynj4chmly.auto.internal:22822): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:03:38,532 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 8fb9ea93-9a15-4db7-8ada-30fd6a36ed53, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,532 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 8fb9ea93-9a15-4db7-8ada-30fd6a36ed53, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" tx_id: "01k9sg7hgh2t6g6pr7v4850xr9" }
2025-11-11 13:03:38,534 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 8fb9ea93-9a15-4db7-8ada-30fd6a36ed53, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS }
2025-11-11 13:03:38,535 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, c60c1be9-5a47-42ca-bc51-2dfd26adfe8d, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,535 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, c60c1be9-5a47-42ca-bc51-2dfd26adfe8d, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" tx_settings { serializable_read_write { } } }
2025-11-11 13:03:38,537 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, c60c1be9-5a47-42ca-bc51-2dfd26adfe8d, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS tx_meta { id: "01k9sg7hh89xfx631ez9fkyw0z" } }
2025-11-11 13:03:38,539 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3af6bc99-2d79-4dee-ba02-022b8fbaee39, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,539 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3af6bc99-2d79-4dee-ba02-022b8fbaee39, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9sg7hh89xfx631ez9fkyw0z" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table85978` (id, vn, vs) VALUES (2, 2, \'2\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 13:03:38,540 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3af6bc99-2d79-4dee-ba02-022b8fbaee39, ghrun-3ynj4chmly.auto.internal:22822): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:03:38,558 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 67cf3e14-30aa-4d4f-be80-a8f0e82e54a7, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,561 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 67cf3e14-30aa-4d4f-be80-a8f0e82e54a7, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" tx_id: "01k9sg7hh89xfx631ez9fkyw0z" }
2025-11-11 13:03:38,563 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 67cf3e14-30aa-4d4f-be80-a8f0e82e54a7, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS }
2025-11-11 13:03:38,563 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 7e930141-2040-450b-9b17-440e1ac577df, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,564 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 7e930141-2040-450b-9b17-440e1ac577df, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" tx_settings { serializable_read_write { } } }
2025-11-11 13:03:38,565 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 7e930141-2040-450b-9b17-440e1ac577df, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS tx_meta { id: "01k9sg7hj57ra4qjx4vp9r1p6g" } }
2025-11-11 13:03:38,566 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fc873fdc-40a6-4eef-885a-acebaf9c5052, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,567 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fc873fdc-40a6-4eef-885a-acebaf9c5052, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9sg7hj57ra4qjx4vp9r1p6g" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table85978` (id, vn, vs) VALUES (3, 3, \'3\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 13:03:38,568 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fc873fdc-40a6-4eef-885a-acebaf9c5052, ghrun-3ynj4chmly.auto.internal:22822): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:03:38,586 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, f2c5e304-b71a-435d-9f33-0d10bdd5f789, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,592 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, f2c5e304-b71a-435d-9f33-0d10bdd5f789, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" tx_id: "01k9sg7hj57ra4qjx4vp9r1p6g" }
2025-11-11 13:03:38,593 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, f2c5e304-b71a-435d-9f33-0d10bdd5f789, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS }
2025-11-11 13:03:38,594 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 7dd7e440-2869-4a79-8365-86044b03132a, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,594 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 7dd7e440-2869-4a79-8365-86044b03132a, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" tx_settings { serializable_read_write { } } }
2025-11-11 13:03:38,596 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 7dd7e440-2869-4a79-8365-86044b03132a, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS tx_meta { id: "01k9sg7hk3178f1ej116gz2ccr" } }
2025-11-11 13:03:38,597 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b98de21-c8b6-4761-b6a7-62867aa20d84, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,597 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b98de21-c8b6-4761-b6a7-62867aa20d84, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9sg7hk3178f1ej116gz2ccr" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table85978` (id, vn, vs) VALUES (4, 4, \'4\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 13:03:38,598 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b98de21-c8b6-4761-b6a7-62867aa20d84, ghrun-3ynj4chmly.auto.internal:22822): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:03:38,616 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 83d8c3b9-390e-4396-9106-06c8c084b1fc, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,616 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 83d8c3b9-390e-4396-9106-06c8c084b1fc, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl" tx_id: "01k9sg7hk3178f1ej116gz2ccr" }
2025-11-11 13:03:38,623 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 83d8c3b9-390e-4396-9106-06c8c084b1fc, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS }
2025-11-11 13:03:38,624 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-11 13:03:38,624 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d069ac75-9c9e-4e28-ac0a-ab8bd114a75f, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,625 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d069ac75-9c9e-4e28-ac0a-ab8bd114a75f, ghrun-3ynj4chmly.auto.internal:22822): request = {  }
2025-11-11 13:03:38,627 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d069ac75-9c9e-4e28-ac0a-ab8bd114a75f, ghrun-3ynj4chmly.auto.internal:22822): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZDNlOTdkMGItZjQ4YTYzNWQtMWRkY2NjNjktZTY4M2U5" node_id: 1 }
2025-11-11 13:03:38,628 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 5d7b71d2-b8ff-4e53-8fa4-5de21bfb0060, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,628 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 5d7b71d2-b8ff-4e53-8fa4-5de21bfb0060, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=ZDNlOTdkMGItZjQ4YTYzNWQtMWRkY2NjNjktZTY4M2U5" }
2025-11-11 13:03:38,629 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 5d7b71d2-b8ff-4e53-8fa4-5de21bfb0060, ghrun-3ynj4chmly.auto.internal:22822): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:03:38,631 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZDNlOTdkMGItZjQ4YTYzNWQtMWRkY2NjNjktZTY4M2U5
2025-11-11 13:03:38,632 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 65a26d6e-b972-4f36-a0c8-454ff0da9ed1, ghrun-3ynj4chmly.auto.internal:22822): creating call state
2025-11-11 13:03:38,632 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 65a26d6e-b972-4f36-a0c8-454ff0da9ed1, ghrun-3ynj4chmly.auto.internal:22822): request = { session_id: "ydb://session/3?node_id=1&id=ZDNlOTdkMGItZjQ4YTYzNWQtMWRkY2NjNjktZTY4M2U5" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT count(*) AS cnt FROM `/Root/insert/table85978`;" } stats_mode: STATS_MODE_NONE }
2025-11-11 13:03:38,633 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 65a26d6e-b972-4f36-a0c8-454ff0da9ed1, ghrun-3ynj4chmly.auto.internal:22822): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:03:38,734 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDNlOTdkMGItZjQ4YTYzNWQtMWRkY2NjNjktZTY4M2U5
2025-11-11 13:03:38,735 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmVmZmJmZTQtYWFhMmE3Y2YtYjU5ZDNjZDctOWMwMjhl
2025-11-11 13:03:38,736 - INFO - ya.test - pytest_runtest_teardown: Test teardown
