2025-10-31 13:48:05,138 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:05,139 - INFO - ya.test - pytest_runtest_setup: test_lost_update_p4
2025-10-31 13:48:05,163 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:05,191 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 13:48:05,204 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_p4)
2025-10-31 13:48:05,205 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, b3b4be98-abe8-4ccc-970a-1fa057a22451, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,206 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, b3b4be98-abe8-4ccc-970a-1fa057a22451, ghrun-e45ihybpyu.auto.internal:20918): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,208 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, b3b4be98-abe8-4ccc-970a-1fa057a22451, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OTU3YjA5ZjItYWM3OTc2NjktM2VkNTQzYjEtOGZiNDYxZWI=" } } } }
2025-10-31 13:48:05,209 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 71784283-d968-441a-ad1b-a0af6552297c, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,213 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 71784283-d968-441a-ad1b-a0af6552297c, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=OTU3YjA5ZjItYWM3OTc2NjktM2VkNTQzYjEtOGZiNDYxZWI=" 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 } } }
2025-10-31 13:48:05,237 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 71784283-d968-441a-ad1b-a0af6552297c, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:48:05,239 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c1c46166-80a2-492a-b1f9-71cbe2b31f98, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,240 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c1c46166-80a2-492a-b1f9-71cbe2b31f98, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=OTU3YjA5ZjItYWM3OTc2NjktM2VkNTQzYjEtOGZiNDYxZWI=" 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 } } }
2025-10-31 13:48:05,255 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c1c46166-80a2-492a-b1f9-71cbe2b31f98, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 13:48:05,256 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 93f2bc11-ec6c-4037-a069-b80a9b1cc887, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,257 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 93f2bc11-ec6c-4037-a069-b80a9b1cc887, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=OTU3YjA5ZjItYWM3OTc2NjktM2VkNTQzYjEtOGZiNDYxZWI=" 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 } } }
2025-10-31 13:48:05,282 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 93f2bc11-ec6c-4037-a069-b80a9b1cc887, ghrun-e45ihybpyu.auto.internal:20918): 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: "01k8x8d0rz90rzm8q7jjr4wade" } } } } }
2025-10-31 13:48:05,284 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 3770c42e-ce3a-4bec-b17c-50c60cfa9e7a, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,285 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 3770c42e-ce3a-4bec-b17c-50c60cfa9e7a, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=OTU3YjA5ZjItYWM3OTc2NjktM2VkNTQzYjEtOGZiNDYxZWI=" 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 } } }
2025-10-31 13:48:05,308 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 3770c42e-ce3a-4bec-b17c-50c60cfa9e7a, ghrun-e45ihybpyu.auto.internal:20918): 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: "01k8x8d0srets0zyd2da55bnqw" } } } } }
2025-10-31 13:48:05,312 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7a4e1417-eb8a-4bac-a9c4-43fafd93df6b, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7a4e1417-eb8a-4bac-a9c4-43fafd93df6b, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=OTU3YjA5ZjItYWM3OTc2NjktM2VkNTQzYjEtOGZiNDYxZWI=" tx_control { tx_id: "01k8x8d0rz90rzm8q7jjr4wade" } query { yql_text: " update test_lost_update_p4 set value = 11 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,337 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7a4e1417-eb8a-4bac-a9c4-43fafd93df6b, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8d0rz90rzm8q7jjr4wade" } } } } }
2025-10-31 13:48:05,340 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 3e892687-3d30-4bdb-be64-f4958e6413fe, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,340 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 3e892687-3d30-4bdb-be64-f4958e6413fe, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=OTU3YjA5ZjItYWM3OTc2NjktM2VkNTQzYjEtOGZiNDYxZWI=" tx_control { tx_id: "01k8x8d0srets0zyd2da55bnqw" } query { yql_text: " update test_lost_update_p4 set value = 11 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,365 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 3e892687-3d30-4bdb-be64-f4958e6413fe, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8d0srets0zyd2da55bnqw" } } } } }
2025-10-31 13:48:05,365 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 633936ec-a579-4ad0-a80c-8856b10f9ed0, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,368 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 633936ec-a579-4ad0-a80c-8856b10f9ed0, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=OTU3YjA5ZjItYWM3OTc2NjktM2VkNTQzYjEtOGZiNDYxZWI=" tx_id: "01k8x8d0rz90rzm8q7jjr4wade" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,376 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 633936ec-a579-4ad0-a80c-8856b10f9ed0, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 13:48:05,377 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, f9136b0e-e7d3-4443-82ee-9956101a1f55, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,396 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, f9136b0e-e7d3-4443-82ee-9956101a1f55, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=OTU3YjA5ZjItYWM3OTc2NjktM2VkNTQzYjEtOGZiNDYxZWI=" tx_id: "01k8x8d0srets0zyd2da55bnqw" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,400 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, f9136b0e-e7d3-4443-82ee-9956101a1f55, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_p4`" issue_code: 2001 severity: 1 } } }
2025-10-31 13:48:05,413 - INFO - ya.test - pytest_runtest_teardown: Test teardown
