2026-03-25 17:14:54,049 - INFO - ya.test.2499227  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:54,049 - INFO - ya.test.2499227  - pytest_runtest_setup: test_lost_update_on_value_p4
2026-03-25 17:14:54,049 - INFO - ya.test.2499227  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:54,049 - INFO - ya.test.2499227  - pytest_runtest_setup: Test setup
2026-03-25 17:14:54,050 - INFO - ya.test.2499227  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_on_value_p4)
2026-03-25 17:14:54,050 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CreateSession, fb3519ec-489a-4f64-8d6d-7fa7a331bc9a, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,050 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CreateSession, fb3519ec-489a-4f64-8d6d-7fa7a331bc9a, ghrun-ve2ie6ihly.auto.internal:65362): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,051 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CreateSession, fb3519ec-489a-4f64-8d6d-7fa7a331bc9a, 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=NDZjNzJlMDEtYjgzMGY2ZS02YWNmNjYzMi00NGU2OTNlOQ%3D%3D" } } } }
2026-03-25 17:14:54,052 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteSchemeQuery, 7989992c-a2e1-4317-9147-310a6999cdcc, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,052 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteSchemeQuery, 7989992c-a2e1-4317-9147-310a6999cdcc, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=NDZjNzJlMDEtYjgzMGY2ZS02YWNmNjYzMi00NGU2OTNlOQ%3D%3D" 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 } } }
2026-03-25 17:14:54,070 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteSchemeQuery, 7989992c-a2e1-4317-9147-310a6999cdcc, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS } }
2026-03-25 17:14:54,071 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, e6c99efc-44e5-4d25-9a5a-1aa95f36ca8e, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,072 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, e6c99efc-44e5-4d25-9a5a-1aa95f36ca8e, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=NDZjNzJlMDEtYjgzMGY2ZS02YWNmNjYzMi00NGU2OTNlOQ%3D%3D" 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 } } }
2026-03-25 17:14:54,087 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, e6c99efc-44e5-4d25-9a5a-1aa95f36ca8e, 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,088 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 0b81a047-8dc5-456b-82e0-259d916638d6, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,089 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 0b81a047-8dc5-456b-82e0-259d916638d6, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=NDZjNzJlMDEtYjgzMGY2ZS02YWNmNjYzMi00NGU2OTNlOQ%3D%3D" 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 } } }
2026-03-25 17:14:54,107 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 0b81a047-8dc5-456b-82e0-259d916638d6, 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: "01kmjzvxrq62d2fffg5nt9zj5f" } } } } }
2026-03-25 17:14:54,108 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 1e8b5853-2444-4b53-b111-0e95021dfe3e, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,109 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 1e8b5853-2444-4b53-b111-0e95021dfe3e, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=NDZjNzJlMDEtYjgzMGY2ZS02YWNmNjYzMi00NGU2OTNlOQ%3D%3D" 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 } } }
2026-03-25 17:14:54,128 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 1e8b5853-2444-4b53-b111-0e95021dfe3e, 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: "01kmjzvxsc0xjkb4wxfmq97w9c" } } } } }
2026-03-25 17:14:54,129 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, bd4233a8-3798-4472-afb9-95d64d471ffb, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,129 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, bd4233a8-3798-4472-afb9-95d64d471ffb, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=NDZjNzJlMDEtYjgzMGY2ZS02YWNmNjYzMi00NGU2OTNlOQ%3D%3D" tx_control { tx_id: "01kmjzvxrq62d2fffg5nt9zj5f" } 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 } } }
2026-03-25 17:14:54,152 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, bd4233a8-3798-4472-afb9-95d64d471ffb, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvxrq62d2fffg5nt9zj5f" } } } } }
2026-03-25 17:14:54,153 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, cbf0c6c9-c503-4d05-b4a8-06fe845710e0, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,154 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, cbf0c6c9-c503-4d05-b4a8-06fe845710e0, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=NDZjNzJlMDEtYjgzMGY2ZS02YWNmNjYzMi00NGU2OTNlOQ%3D%3D" tx_id: "01kmjzvxrq62d2fffg5nt9zj5f" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,160 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, cbf0c6c9-c503-4d05-b4a8-06fe845710e0, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-03-25 17:14:54,161 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, d8a47e26-e038-4510-a263-906fd8d21040, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,161 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, d8a47e26-e038-4510-a263-906fd8d21040, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=NDZjNzJlMDEtYjgzMGY2ZS02YWNmNjYzMi00NGU2OTNlOQ%3D%3D" tx_control { tx_id: "01kmjzvxsc0xjkb4wxfmq97w9c" } 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 } } }
2026-03-25 17:14:54,184 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, d8a47e26-e038-4510-a263-906fd8d21040, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvxsc0xjkb4wxfmq97w9c" } } } } }
2026-03-25 17:14:54,185 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, a737723d-937d-496a-993c-ea9ee0833dcf, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,186 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, a737723d-937d-496a-993c-ea9ee0833dcf, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=NDZjNzJlMDEtYjgzMGY2ZS02YWNmNjYzMi00NGU2OTNlOQ%3D%3D" tx_id: "01kmjzvxsc0xjkb4wxfmq97w9c" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,189 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, a737723d-937d-496a-993c-ea9ee0833dcf, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_on_value_p4`." issue_code: 2001 severity: 1 } } }
2026-03-25 17:14:54,191 - INFO - ya.test.2499227  - pytest_runtest_teardown: Test teardown
