2025-10-31 13:48:05,425 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:05,425 - INFO - ya.test - pytest_runtest_setup: test_lost_update_on_value_p4
2025-10-31 13:48:05,428 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:05,430 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 13:48:05,434 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_on_value_p4)
2025-10-31 13:48:05,436 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, de801b40-692a-4701-8b81-15a069969f10, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,437 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, de801b40-692a-4701-8b81-15a069969f10, ghrun-e45ihybpyu.auto.internal:20918): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,441 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, de801b40-692a-4701-8b81-15a069969f10, 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=YmJlNDUzNzItYjM4MjkwNWEtZjVhNzUxMWItZWNkYjIwZWU=" } } } }
2025-10-31 13:48:05,444 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 5abfdb60-b6f8-481d-b57a-9b4b33241a1f, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,444 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 5abfdb60-b6f8-481d-b57a-9b4b33241a1f, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YmJlNDUzNzItYjM4MjkwNWEtZjVhNzUxMWItZWNkYjIwZWU=" yql_text: "create table test_lost_update_on_value_p4 (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,478 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 5abfdb60-b6f8-481d-b57a-9b4b33241a1f, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:48:05,480 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 9b12fc40-bd46-4c10-a8c3-a8c247726b9d, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,480 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 9b12fc40-bd46-4c10-a8c3-a8c247726b9d, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YmJlNDUzNzItYjM4MjkwNWEtZjVhNzUxMWItZWNkYjIwZWU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_lost_update_on_value_p4 (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,499 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 9b12fc40-bd46-4c10-a8c3-a8c247726b9d, 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,504 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 9eafd4eb-a8b0-46df-ae12-578ebcd974c3, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,511 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 9eafd4eb-a8b0-46df-ae12-578ebcd974c3, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YmJlNDUzNzItYjM4MjkwNWEtZjVhNzUxMWItZWNkYjIwZWU=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value from test_lost_update_on_value_p4 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,531 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 9eafd4eb-a8b0-46df-ae12-578ebcd974c3, 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 } } } } 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: "01k8x8d10r2t33eja4r3ebamdg" } } } } }
2025-10-31 13:48:05,533 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5e0de9c4-8b9e-4840-b5e1-501412f17ca3, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,533 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5e0de9c4-8b9e-4840-b5e1-501412f17ca3, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YmJlNDUzNzItYjM4MjkwNWEtZjVhNzUxMWItZWNkYjIwZWU=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value from test_lost_update_on_value_p4 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,555 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5e0de9c4-8b9e-4840-b5e1-501412f17ca3, 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 } } } } 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: "01k8x8d11e73dpvcw9dq5rn876" } } } } }
2025-10-31 13:48:05,569 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6c372624-acd3-4408-92f3-29618c5b247c, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,569 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6c372624-acd3-4408-92f3-29618c5b247c, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YmJlNDUzNzItYjM4MjkwNWEtZjVhNzUxMWItZWNkYjIwZWU=" tx_control { tx_id: "01k8x8d10r2t33eja4r3ebamdg" } query { yql_text: " update test_lost_update_on_value_p4 set value = 11 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,600 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6c372624-acd3-4408-92f3-29618c5b247c, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8d10r2t33eja4r3ebamdg" } } } } }
2025-10-31 13:48:05,605 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 68102ffd-a0a5-4281-987b-dfd46582d6c5, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,616 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 68102ffd-a0a5-4281-987b-dfd46582d6c5, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YmJlNDUzNzItYjM4MjkwNWEtZjVhNzUxMWItZWNkYjIwZWU=" tx_id: "01k8x8d10r2t33eja4r3ebamdg" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,628 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 68102ffd-a0a5-4281-987b-dfd46582d6c5, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 13:48:05,636 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0dfda98c-b499-438e-93e9-278d82ba7bdd, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,638 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0dfda98c-b499-438e-93e9-278d82ba7bdd, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YmJlNDUzNzItYjM4MjkwNWEtZjVhNzUxMWItZWNkYjIwZWU=" tx_control { tx_id: "01k8x8d11e73dpvcw9dq5rn876" } query { yql_text: " update test_lost_update_on_value_p4 set value = 12 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,662 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0dfda98c-b499-438e-93e9-278d82ba7bdd, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8d11e73dpvcw9dq5rn876" } } } } }
2025-10-31 13:48:05,663 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 32709c47-b0cc-4020-9eff-db8957b808db, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,664 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 32709c47-b0cc-4020-9eff-db8957b808db, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YmJlNDUzNzItYjM4MjkwNWEtZjVhNzUxMWItZWNkYjIwZWU=" tx_id: "01k8x8d11e73dpvcw9dq5rn876" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,668 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 32709c47-b0cc-4020-9eff-db8957b808db, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_on_value_p4`" issue_code: 2001 severity: 1 } } }
2025-10-31 13:48:05,696 - INFO - ya.test - pytest_runtest_teardown: Test teardown
