2026-03-25 17:14:54,194 - INFO - ya.test.2499227  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:54,194 - INFO - ya.test.2499227  - pytest_runtest_setup: test_lost_update_on_value_with_upsert_p4
2026-03-25 17:14:54,195 - INFO - ya.test.2499227  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:54,195 - INFO - ya.test.2499227  - pytest_runtest_setup: Test setup
2026-03-25 17:14:54,196 - INFO - ya.test.2499227  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_on_value_with_upsert_p4)
2026-03-25 17:14:54,196 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CreateSession, 088953fe-1ed3-439d-a9fb-1231decf6c87, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,197 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CreateSession, 088953fe-1ed3-439d-a9fb-1231decf6c87, ghrun-ve2ie6ihly.auto.internal:65362): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,199 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CreateSession, 088953fe-1ed3-439d-a9fb-1231decf6c87, 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=OTQ0ODM3OGMtODJiYmY5YTItMzU4MDJiMDAtZjE3NGExZmY%3D" } } } }
2026-03-25 17:14:54,199 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteSchemeQuery, e72c687f-fe98-40ed-be6b-d121d7634bc9, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,200 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteSchemeQuery, e72c687f-fe98-40ed-be6b-d121d7634bc9, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=OTQ0ODM3OGMtODJiYmY5YTItMzU4MDJiMDAtZjE3NGExZmY%3D" yql_text: "create table test_lost_update_on_value_with_upsert_p4 (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,216 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteSchemeQuery, e72c687f-fe98-40ed-be6b-d121d7634bc9, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS } }
2026-03-25 17:14:54,217 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, dbc13729-a51c-4c13-b12e-a402cfbf7aac, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,218 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, dbc13729-a51c-4c13-b12e-a402cfbf7aac, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=OTQ0ODM3OGMtODJiYmY5YTItMzU4MDJiMDAtZjE3NGExZmY%3D" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_lost_update_on_value_with_upsert_p4 (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,230 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, dbc13729-a51c-4c13-b12e-a402cfbf7aac, 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:54,231 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 0247bc4f-f6ac-4813-98b3-42650cda6156, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,232 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 0247bc4f-f6ac-4813-98b3-42650cda6156, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=OTQ0ODM3OGMtODJiYmY5YTItMzU4MDJiMDAtZjE3NGExZmY%3D" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value from test_lost_update_on_value_with_upsert_p4 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,247 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 0247bc4f-f6ac-4813-98b3-42650cda6156, 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 } } } } columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 1 } items { int32_value: 10 } } format: FORMAT_VALUE } tx_meta { id: "01kmjzvxx41askzpt95d7dtbsa" } } } } }
2026-03-25 17:14:54,248 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, b2901c22-4662-411f-b71d-8777d19d455f, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,249 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, b2901c22-4662-411f-b71d-8777d19d455f, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=OTQ0ODM3OGMtODJiYmY5YTItMzU4MDJiMDAtZjE3NGExZmY%3D" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value from test_lost_update_on_value_with_upsert_p4 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,265 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, b2901c22-4662-411f-b71d-8777d19d455f, 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 } } } } columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 1 } items { int32_value: 10 } } format: FORMAT_VALUE } tx_meta { id: "01kmjzvxxpa9hwd3dcaqbvaxh8" } } } } }
2026-03-25 17:14:54,266 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, c7570882-0236-4ab3-bed5-7cabb43ce845, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,267 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, c7570882-0236-4ab3-bed5-7cabb43ce845, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=OTQ0ODM3OGMtODJiYmY5YTItMzU4MDJiMDAtZjE3NGExZmY%3D" tx_control { tx_id: "01kmjzvxx41askzpt95d7dtbsa" } query { yql_text: " upsert into test_lost_update_on_value_with_upsert_p4 (id, value) VALUES (1, 11);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,278 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, c7570882-0236-4ab3-bed5-7cabb43ce845, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvxx41askzpt95d7dtbsa" } } } } }
2026-03-25 17:14:54,279 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, 45160bcd-0a7e-49f1-b542-a66766e826e4, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,280 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, 45160bcd-0a7e-49f1-b542-a66766e826e4, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=OTQ0ODM3OGMtODJiYmY5YTItMzU4MDJiMDAtZjE3NGExZmY%3D" tx_id: "01kmjzvxx41askzpt95d7dtbsa" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,284 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, 45160bcd-0a7e-49f1-b542-a66766e826e4, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-03-25 17:14:54,284 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 7aa9dd92-1302-4867-8bde-02625fc18e4d, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,285 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 7aa9dd92-1302-4867-8bde-02625fc18e4d, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=OTQ0ODM3OGMtODJiYmY5YTItMzU4MDJiMDAtZjE3NGExZmY%3D" tx_control { tx_id: "01kmjzvxxpa9hwd3dcaqbvaxh8" } query { yql_text: " upsert into test_lost_update_on_value_with_upsert_p4 (id, value) VALUES (1, 12);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,295 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 7aa9dd92-1302-4867-8bde-02625fc18e4d, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvxxpa9hwd3dcaqbvaxh8" } } } } }
2026-03-25 17:14:54,296 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, 47a4d08c-46e7-4a4c-a566-da1f98c81e35, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,296 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, 47a4d08c-46e7-4a4c-a566-da1f98c81e35, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=OTQ0ODM3OGMtODJiYmY5YTItMzU4MDJiMDAtZjE3NGExZmY%3D" tx_id: "01kmjzvxxpa9hwd3dcaqbvaxh8" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,299 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, 47a4d08c-46e7-4a4c-a566-da1f98c81e35, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_on_value_with_upsert_p4`." issue_code: 2001 severity: 1 } issues { message: "Operation is aborting because locks are not valid" issue_code: 2001 severity: 1 } } }
2026-03-25 17:14:54,301 - INFO - ya.test.2499227  - pytest_runtest_teardown: Test teardown
