2025-12-08 23:27:55,598 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:55,598 - INFO - ya.test - pytest_runtest_setup: test_lost_update_p4
2025-12-08 23:27:55,598 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:55,599 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-08 23:27:55,600 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_p4)
2025-12-08 23:27:55,600 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 931eec0c-b6b5-4112-b6b4-748eb01dfdc8, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:55,615 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 931eec0c-b6b5-4112-b6b4-748eb01dfdc8, ghrun-sfoiezpm3a.auto.internal:1948): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:55,626 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 931eec0c-b6b5-4112-b6b4-748eb01dfdc8, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZjU5YmEyNzUtNDU3MzNlY2YtOTViNDZjMGUtMTNmODgyZQ==" } } } }
2025-12-08 23:27:55,627 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 12d362f8-0f53-49cb-955a-9c5f737eeb59, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:55,628 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 12d362f8-0f53-49cb-955a-9c5f737eeb59, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=ZjU5YmEyNzUtNDU3MzNlY2YtOTViNDZjMGUtMTNmODgyZQ==" 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-12-08 23:27:55,666 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 12d362f8-0f53-49cb-955a-9c5f737eeb59, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:27:55,678 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 2d71906e-7430-47f5-ad7f-e026ce2f7bae, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:55,685 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 2d71906e-7430-47f5-ad7f-e026ce2f7bae, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=ZjU5YmEyNzUtNDU3MzNlY2YtOTViNDZjMGUtMTNmODgyZQ==" 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-12-08 23:27:55,727 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 2d71906e-7430-47f5-ad7f-e026ce2f7bae, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:27:55,728 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 99f803b0-ae9a-4bda-bedf-d2ab656a6aeb, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:55,737 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 99f803b0-ae9a-4bda-bedf-d2ab656a6aeb, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=ZjU5YmEyNzUtNDU3MzNlY2YtOTViNDZjMGUtMTNmODgyZQ==" 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-12-08 23:27:55,785 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 99f803b0-ae9a-4bda-bedf-d2ab656a6aeb, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04p1vz7ehx6f45ex7rjqbh" } } } } }
2025-12-08 23:27:55,789 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c289e857-7e99-42b5-b9a2-dffdae9b7286, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:55,790 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c289e857-7e99-42b5-b9a2-dffdae9b7286, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=ZjU5YmEyNzUtNDU3MzNlY2YtOTViNDZjMGUtMTNmODgyZQ==" 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-12-08 23:27:55,834 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c289e857-7e99-42b5-b9a2-dffdae9b7286, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04p1xffer79b58p1h4tks6" } } } } }
2025-12-08 23:27:55,835 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c79185a5-1c0f-4d91-b13b-56bbac9c30a5, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:55,869 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c79185a5-1c0f-4d91-b13b-56bbac9c30a5, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=ZjU5YmEyNzUtNDU3MzNlY2YtOTViNDZjMGUtMTNmODgyZQ==" tx_control { tx_id: "01kc04p1vz7ehx6f45ex7rjqbh" } 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-12-08 23:27:55,897 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c79185a5-1c0f-4d91-b13b-56bbac9c30a5, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p1vz7ehx6f45ex7rjqbh" } } } } }
2025-12-08 23:27:55,898 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 2e7c3dc0-da78-4887-8781-8611e444ec66, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:55,899 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 2e7c3dc0-da78-4887-8781-8611e444ec66, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=ZjU5YmEyNzUtNDU3MzNlY2YtOTViNDZjMGUtMTNmODgyZQ==" tx_control { tx_id: "01kc04p1xffer79b58p1h4tks6" } 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-12-08 23:27:55,923 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 2e7c3dc0-da78-4887-8781-8611e444ec66, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p1xffer79b58p1h4tks6" } } } } }
2025-12-08 23:27:55,930 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 345056a6-3e7c-40ad-bcbb-eb84d70f2feb, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:55,938 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 345056a6-3e7c-40ad-bcbb-eb84d70f2feb, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=ZjU5YmEyNzUtNDU3MzNlY2YtOTViNDZjMGUtMTNmODgyZQ==" tx_id: "01kc04p1vz7ehx6f45ex7rjqbh" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:55,972 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 345056a6-3e7c-40ad-bcbb-eb84d70f2feb, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-08 23:27:55,975 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 26187e93-0c0c-4bc6-a2eb-c34cc8fccb40, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:55,976 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 26187e93-0c0c-4bc6-a2eb-c34cc8fccb40, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=ZjU5YmEyNzUtNDU3MzNlY2YtOTViNDZjMGUtMTNmODgyZQ==" tx_id: "01kc04p1xffer79b58p1h4tks6" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:55,983 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 26187e93-0c0c-4bc6-a2eb-c34cc8fccb40, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_p4`" issue_code: 2001 severity: 1 } } }
2025-12-08 23:27:55,993 - INFO - ya.test - pytest_runtest_teardown: Test teardown
