2026-08-19 13:41:24,771 - INFO - ya.test.1603699  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:41:24,772 - INFO - ya.test.1603699  - pytest_runtest_setup: test_lost_update_p4
2026-08-19 13:41:24,772 - INFO - ya.test.1603699  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:41:24,772 - INFO - ya.test.1603699  - pytest_runtest_setup: Test setup
2026-08-19 13:41:24,773 - INFO - ya.test.1603699  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_p4)
2026-08-19 13:41:24,773 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CreateSession, 44c3e5a6-e62a-49bb-996d-cd0b48c648ae, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,774 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CreateSession, 44c3e5a6-e62a-49bb-996d-cd0b48c648ae, ghrun-4q22hfe2g4.auto.internal:22374): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,775 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CreateSession, 44c3e5a6-e62a-49bb-996d-cd0b48c648ae, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZjhkNGYzOWUtODI0OWQyM2ItMTRmYmE0ZjctYTE1YjJmMTM=" } } } }
2026-08-19 13:41:24,776 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteSchemeQuery, 6d72201c-81b3-4249-85a4-8cbfff9bfd4f, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,776 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteSchemeQuery, 6d72201c-81b3-4249-85a4-8cbfff9bfd4f, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZjhkNGYzOWUtODI0OWQyM2ItMTRmYmE0ZjctYTE1YjJmMTM=" yql_text: "create table test_lost_update_p4 (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,790 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteSchemeQuery, 6d72201c-81b3-4249-85a4-8cbfff9bfd4f, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:41:24,790 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 9924513d-2007-48aa-be0b-7a35b26ab947, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,791 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 9924513d-2007-48aa-be0b-7a35b26ab947, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZjhkNGYzOWUtODI0OWQyM2ItMTRmYmE0ZjctYTE1YjJmMTM=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_lost_update_p4 (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,801 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 9924513d-2007-48aa-be0b-7a35b26ab947, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2026-08-19 13:41:24,802 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, c0737d52-ec1e-4b3b-a36c-5d12ce4fc691, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,802 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, c0737d52-ec1e-4b3b-a36c-5d12ce4fc691, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZjhkNGYzOWUtODI0OWQyM2ItMTRmYmE0ZjctYTE1YjJmMTM=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id from test_lost_update_p4 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,815 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, c0737d52-ec1e-4b3b-a36c-5d12ce4fc691, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { id: "01m0d42npdbenp9kfvd51cc460" } } } } }
2026-08-19 13:41:24,816 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 5de8ca07-3d1d-42ca-a6b9-5c91c37d9fe7, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,817 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 5de8ca07-3d1d-42ca-a6b9-5c91c37d9fe7, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZjhkNGYzOWUtODI0OWQyM2ItMTRmYmE0ZjctYTE1YjJmMTM=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id from test_lost_update_p4 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,829 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 5de8ca07-3d1d-42ca-a6b9-5c91c37d9fe7, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { id: "01m0d42npv831y8s1nc7d4drb9" } } } } }
2026-08-19 13:41:24,830 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 8d561fad-fbee-409d-9a90-b6ef73851671, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,830 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 8d561fad-fbee-409d-9a90-b6ef73851671, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZjhkNGYzOWUtODI0OWQyM2ItMTRmYmE0ZjctYTE1YjJmMTM=" tx_control { tx_id: "01m0d42npdbenp9kfvd51cc460" } query { yql_text: " update test_lost_update_p4 set value = 11 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,846 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 8d561fad-fbee-409d-9a90-b6ef73851671, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42npdbenp9kfvd51cc460" } } } } }
2026-08-19 13:41:24,847 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 42871e63-61c6-461b-be5e-e55d79c205e5, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,847 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 42871e63-61c6-461b-be5e-e55d79c205e5, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZjhkNGYzOWUtODI0OWQyM2ItMTRmYmE0ZjctYTE1YjJmMTM=" tx_control { tx_id: "01m0d42npv831y8s1nc7d4drb9" } query { yql_text: " update test_lost_update_p4 set value = 11 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,865 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 42871e63-61c6-461b-be5e-e55d79c205e5, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42npv831y8s1nc7d4drb9" } } } } }
2026-08-19 13:41:24,866 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, 430d9dcb-8a8f-4f74-b432-e1ce11c92849, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,866 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, 430d9dcb-8a8f-4f74-b432-e1ce11c92849, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZjhkNGYzOWUtODI0OWQyM2ItMTRmYmE0ZjctYTE1YjJmMTM=" tx_id: "01m0d42npdbenp9kfvd51cc460" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,870 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, 430d9dcb-8a8f-4f74-b432-e1ce11c92849, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-08-19 13:41:24,871 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, b4f60d9a-139d-46a3-92b1-35a9b81503c5, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,872 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, b4f60d9a-139d-46a3-92b1-35a9b81503c5, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZjhkNGYzOWUtODI0OWQyM2ItMTRmYmE0ZjctYTE1YjJmMTM=" tx_id: "01m0d42npv831y8s1nc7d4drb9" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,875 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, b4f60d9a-139d-46a3-92b1-35a9b81503c5, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_p4`." issue_code: 2001 severity: 1 } } }
2026-08-19 13:41:24,877 - INFO - ya.test.1603699  - pytest_runtest_teardown: Test teardown
