2025-11-26 18:41:40,637 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:40,674 - INFO - ya.test.2332018  - pytest_runtest_setup: test_lost_update_p4
2025-11-26 18:41:40,675 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:40,675 - INFO - ya.test.2332018  - pytest_runtest_setup: Test setup
2025-11-26 18:41:40,678 - INFO - ya.test.2332018  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_p4)
2025-11-26 18:41:40,679 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CreateSession, bd408967-18af-4f65-a01d-7853a6de276c, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,679 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CreateSession, bd408967-18af-4f65-a01d-7853a6de276c, ghrun-ihttfokzrq.auto.internal:8570): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,682 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CreateSession, bd408967-18af-4f65-a01d-7853a6de276c, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YTJlYzc2NjktODEyMmJhMGYtNTY4NjIzMTQtN2RkZGM2YQ==" } } } }
2025-11-26 18:41:40,683 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteSchemeQuery, 7926bdc7-3b42-4473-9790-c4feda0647cc, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,684 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteSchemeQuery, 7926bdc7-3b42-4473-9790-c4feda0647cc, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YTJlYzc2NjktODEyMmJhMGYtNTY4NjIzMTQtN2RkZGM2YQ==" 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-11-26 18:41:40,711 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteSchemeQuery, 7926bdc7-3b42-4473-9790-c4feda0647cc, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:41:40,712 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 4010b316-2e17-49b1-857c-e6f3cddb191a, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,713 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 4010b316-2e17-49b1-857c-e6f3cddb191a, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YTJlYzc2NjktODEyMmJhMGYtNTY4NjIzMTQtN2RkZGM2YQ==" 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-11-26 18:41:40,734 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 4010b316-2e17-49b1-857c-e6f3cddb191a, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-26 18:41:40,735 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, e43d54c7-b683-4a8d-8e3d-78f4a26baf16, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,735 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, e43d54c7-b683-4a8d-8e3d-78f4a26baf16, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YTJlYzc2NjktODEyMmJhMGYtNTY4NjIzMTQtN2RkZGM2YQ==" 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-11-26 18:41:40,759 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, e43d54c7-b683-4a8d-8e3d-78f4a26baf16, ghrun-ihttfokzrq.auto.internal:8570): 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: "01kb0qh9ck2s8t51558c4rbzk7" } } } } }
2025-11-26 18:41:40,759 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 3d2a704b-b739-4bdd-b619-0a892a15f3fd, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,760 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 3d2a704b-b739-4bdd-b619-0a892a15f3fd, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YTJlYzc2NjktODEyMmJhMGYtNTY4NjIzMTQtN2RkZGM2YQ==" 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-11-26 18:41:40,789 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 3d2a704b-b739-4bdd-b619-0a892a15f3fd, ghrun-ihttfokzrq.auto.internal:8570): 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: "01kb0qh9djbbmjyzaab9ayjdnz" } } } } }
2025-11-26 18:41:40,790 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, cf7209ff-29f1-46cc-9776-dcf8c6e0cb4c, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,791 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, cf7209ff-29f1-46cc-9776-dcf8c6e0cb4c, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YTJlYzc2NjktODEyMmJhMGYtNTY4NjIzMTQtN2RkZGM2YQ==" tx_control { tx_id: "01kb0qh9ck2s8t51558c4rbzk7" } 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-11-26 18:41:40,817 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, cf7209ff-29f1-46cc-9776-dcf8c6e0cb4c, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh9ck2s8t51558c4rbzk7" } } } } }
2025-11-26 18:41:40,818 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 02cc3c68-4d72-4fa6-bddb-ae231e3ccb7d, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,818 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 02cc3c68-4d72-4fa6-bddb-ae231e3ccb7d, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YTJlYzc2NjktODEyMmJhMGYtNTY4NjIzMTQtN2RkZGM2YQ==" tx_control { tx_id: "01kb0qh9djbbmjyzaab9ayjdnz" } 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-11-26 18:41:40,849 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 02cc3c68-4d72-4fa6-bddb-ae231e3ccb7d, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh9djbbmjyzaab9ayjdnz" } } } } }
2025-11-26 18:41:40,852 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, 56bd3c33-1467-45da-a14b-cde7f4af0645, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,853 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, 56bd3c33-1467-45da-a14b-cde7f4af0645, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YTJlYzc2NjktODEyMmJhMGYtNTY4NjIzMTQtN2RkZGM2YQ==" tx_id: "01kb0qh9ck2s8t51558c4rbzk7" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,861 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, 56bd3c33-1467-45da-a14b-cde7f4af0645, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-11-26 18:41:40,862 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, 4d2cd047-447d-4c43-9da9-284f955bd405, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,862 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, 4d2cd047-447d-4c43-9da9-284f955bd405, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YTJlYzc2NjktODEyMmJhMGYtNTY4NjIzMTQtN2RkZGM2YQ==" tx_id: "01kb0qh9djbbmjyzaab9ayjdnz" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,866 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, 4d2cd047-447d-4c43-9da9-284f955bd405, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_p4`" issue_code: 2001 severity: 1 } } }
2025-11-26 18:41:40,868 - INFO - ya.test.2332018  - pytest_runtest_teardown: Test teardown
