2026-03-25 17:14:53,929 - INFO - ya.test.2499227  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:53,929 - INFO - ya.test.2499227  - pytest_runtest_setup: test_lost_update_p4
2026-03-25 17:14:53,929 - INFO - ya.test.2499227  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:53,930 - INFO - ya.test.2499227  - pytest_runtest_setup: Test setup
2026-03-25 17:14:53,930 - INFO - ya.test.2499227  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_p4)
2026-03-25 17:14:53,931 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CreateSession, 0220e440-0c65-4e9c-bc99-ec3adc3a8802, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,931 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CreateSession, 0220e440-0c65-4e9c-bc99-ec3adc3a8802, ghrun-ve2ie6ihly.auto.internal:65362): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:53,932 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CreateSession, 0220e440-0c65-4e9c-bc99-ec3adc3a8802, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZjY4ODQyNzYtN2Q5Mzg2YmUtODRhZmRlZmEtMTE2MDIwZTE%3D" } } } }
2026-03-25 17:14:53,932 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteSchemeQuery, 0f0464cc-e27b-4e63-89b3-b85cba0fa964, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,933 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteSchemeQuery, 0f0464cc-e27b-4e63-89b3-b85cba0fa964, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjY4ODQyNzYtN2Q5Mzg2YmUtODRhZmRlZmEtMTE2MDIwZTE%3D" 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-03-25 17:14:53,948 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteSchemeQuery, 0f0464cc-e27b-4e63-89b3-b85cba0fa964, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS } }
2026-03-25 17:14:53,949 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, b0ce07e7-1f0b-4147-9253-9bc347484f07, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,950 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, b0ce07e7-1f0b-4147-9253-9bc347484f07, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjY4ODQyNzYtN2Q5Mzg2YmUtODRhZmRlZmEtMTE2MDIwZTE%3D" 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-03-25 17:14:53,964 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, b0ce07e7-1f0b-4147-9253-9bc347484f07, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2026-03-25 17:14:53,965 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, c2c9a08e-8f51-4097-a7f9-f798e7796938, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,965 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, c2c9a08e-8f51-4097-a7f9-f798e7796938, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjY4ODQyNzYtN2Q5Mzg2YmUtODRhZmRlZmEtMTE2MDIwZTE%3D" 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-03-25 17:14:53,982 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, c2c9a08e-8f51-4097-a7f9-f798e7796938, ghrun-ve2ie6ihly.auto.internal:65362): 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: "01kmjzvxmtb1exbnz0y75a6qq7" } } } } }
2026-03-25 17:14:53,983 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, f1d4e227-ef77-4a2e-8951-29ff7e896b21, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,984 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, f1d4e227-ef77-4a2e-8951-29ff7e896b21, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjY4ODQyNzYtN2Q5Mzg2YmUtODRhZmRlZmEtMTE2MDIwZTE%3D" 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-03-25 17:14:53,999 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, f1d4e227-ef77-4a2e-8951-29ff7e896b21, ghrun-ve2ie6ihly.auto.internal:65362): 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: "01kmjzvxnd2mzrm1ysjte7m81t" } } } } }
2026-03-25 17:14:54,000 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 8c093034-f059-417f-a42e-1c70e5a05d5f, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,000 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 8c093034-f059-417f-a42e-1c70e5a05d5f, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjY4ODQyNzYtN2Q5Mzg2YmUtODRhZmRlZmEtMTE2MDIwZTE%3D" tx_control { tx_id: "01kmjzvxmtb1exbnz0y75a6qq7" } 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-03-25 17:14:54,017 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 8c093034-f059-417f-a42e-1c70e5a05d5f, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvxmtb1exbnz0y75a6qq7" } } } } }
2026-03-25 17:14:54,018 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, e57ec41d-2409-45c8-9016-82ef381a0f88, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,018 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, e57ec41d-2409-45c8-9016-82ef381a0f88, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjY4ODQyNzYtN2Q5Mzg2YmUtODRhZmRlZmEtMTE2MDIwZTE%3D" tx_control { tx_id: "01kmjzvxnd2mzrm1ysjte7m81t" } 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-03-25 17:14:54,035 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, e57ec41d-2409-45c8-9016-82ef381a0f88, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvxnd2mzrm1ysjte7m81t" } } } } }
2026-03-25 17:14:54,036 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, bbfa45e5-119e-471a-a996-1e2edc7bf9e0, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,037 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, bbfa45e5-119e-471a-a996-1e2edc7bf9e0, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjY4ODQyNzYtN2Q5Mzg2YmUtODRhZmRlZmEtMTE2MDIwZTE%3D" tx_id: "01kmjzvxmtb1exbnz0y75a6qq7" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,041 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, bbfa45e5-119e-471a-a996-1e2edc7bf9e0, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-03-25 17:14:54,042 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, 84e3ed34-e8fc-4796-b50a-7a4226941a23, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,043 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, 84e3ed34-e8fc-4796-b50a-7a4226941a23, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjY4ODQyNzYtN2Q5Mzg2YmUtODRhZmRlZmEtMTE2MDIwZTE%3D" tx_id: "01kmjzvxnd2mzrm1ysjte7m81t" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,045 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, 84e3ed34-e8fc-4796-b50a-7a4226941a23, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_p4`." issue_code: 2001 severity: 1 } } }
2026-03-25 17:14:54,047 - INFO - ya.test.2499227  - pytest_runtest_teardown: Test teardown
