2026-08-19 13:41:24,994 - INFO - ya.test.1603699  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:41:24,994 - INFO - ya.test.1603699  - pytest_runtest_setup: test_lost_update_on_value_with_upsert_p4
2026-08-19 13:41:24,994 - INFO - ya.test.1603699  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:41:24,994 - INFO - ya.test.1603699  - pytest_runtest_setup: Test setup
2026-08-19 13:41:24,996 - INFO - ya.test.1603699  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_on_value_with_upsert_p4)
2026-08-19 13:41:24,996 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CreateSession, f3816766-e061-4c70-8ba4-e86734b49931, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,996 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CreateSession, f3816766-e061-4c70-8ba4-e86734b49931, ghrun-4q22hfe2g4.auto.internal:22374): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,998 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CreateSession, f3816766-e061-4c70-8ba4-e86734b49931, 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=OTE5N2RlODYtMjBmMmE4OGItNzM1NjdiZDctYWU1ODg2YWI=" } } } }
2026-08-19 13:41:24,998 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteSchemeQuery, c30d2f19-8aa3-485e-8ffa-3f3ca6f1afa7, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,999 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteSchemeQuery, c30d2f19-8aa3-485e-8ffa-3f3ca6f1afa7, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OTE5N2RlODYtMjBmMmE4OGItNzM1NjdiZDctYWU1ODg2YWI=" 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-08-19 13:41:25,015 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteSchemeQuery, c30d2f19-8aa3-485e-8ffa-3f3ca6f1afa7, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:41:25,016 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 3b56c012-78a2-43c3-8f02-10ff63515419, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,016 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 3b56c012-78a2-43c3-8f02-10ff63515419, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OTE5N2RlODYtMjBmMmE4OGItNzM1NjdiZDctYWU1ODg2YWI=" 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-08-19 13:41:25,028 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 3b56c012-78a2-43c3-8f02-10ff63515419, 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:25,029 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 4cfa871a-9eae-472b-af76-4f6d730ca74d, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,029 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 4cfa871a-9eae-472b-af76-4f6d730ca74d, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OTE5N2RlODYtMjBmMmE4OGItNzM1NjdiZDctYWU1ODg2YWI=" 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-08-19 13:41:25,043 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 4cfa871a-9eae-472b-af76-4f6d730ca74d, 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 } } } } 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: "01m0d42nxg6dr1sfp0krs4j0b4" } } } } }
2026-08-19 13:41:25,044 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, e686727a-cfc9-48fa-b19e-72fb187e1786, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,044 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, e686727a-cfc9-48fa-b19e-72fb187e1786, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OTE5N2RlODYtMjBmMmE4OGItNzM1NjdiZDctYWU1ODg2YWI=" 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-08-19 13:41:25,058 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, e686727a-cfc9-48fa-b19e-72fb187e1786, 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 } } } } 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: "01m0d42nxzdtchrkmv1ehr9w4b" } } } } }
2026-08-19 13:41:25,059 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 16e6f94c-65ac-4199-bbb9-8bd2144e4934, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,060 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 16e6f94c-65ac-4199-bbb9-8bd2144e4934, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OTE5N2RlODYtMjBmMmE4OGItNzM1NjdiZDctYWU1ODg2YWI=" tx_control { tx_id: "01m0d42nxg6dr1sfp0krs4j0b4" } 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-08-19 13:41:25,068 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 16e6f94c-65ac-4199-bbb9-8bd2144e4934, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42nxg6dr1sfp0krs4j0b4" } } } } }
2026-08-19 13:41:25,068 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, 746b5ee1-0630-4a9a-b182-710779ffa937, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,069 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, 746b5ee1-0630-4a9a-b182-710779ffa937, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OTE5N2RlODYtMjBmMmE4OGItNzM1NjdiZDctYWU1ODg2YWI=" tx_id: "01m0d42nxg6dr1sfp0krs4j0b4" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:25,073 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, 746b5ee1-0630-4a9a-b182-710779ffa937, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-08-19 13:41:25,073 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 37fa7878-1ca8-4f08-8b26-58982631407b, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,074 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 37fa7878-1ca8-4f08-8b26-58982631407b, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OTE5N2RlODYtMjBmMmE4OGItNzM1NjdiZDctYWU1ODg2YWI=" tx_control { tx_id: "01m0d42nxzdtchrkmv1ehr9w4b" } 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-08-19 13:41:25,081 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 37fa7878-1ca8-4f08-8b26-58982631407b, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42nxzdtchrkmv1ehr9w4b" } } } } }
2026-08-19 13:41:25,082 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, 285fbb51-beef-4cd2-a439-1288784b9b21, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,082 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, 285fbb51-beef-4cd2-a439-1288784b9b21, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OTE5N2RlODYtMjBmMmE4OGItNzM1NjdiZDctYWU1ODg2YWI=" tx_id: "01m0d42nxzdtchrkmv1ehr9w4b" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:25,085 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, 285fbb51-beef-4cd2-a439-1288784b9b21, ghrun-4q22hfe2g4.auto.internal:22374): 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-08-19 13:41:25,087 - INFO - ya.test.1603699  - pytest_runtest_teardown: Test teardown
