2025-10-31 13:48:05,703 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:05,736 - INFO - ya.test - pytest_runtest_setup: test_lost_update_on_value_with_upsert_p4
2025-10-31 13:48:05,737 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:05,737 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 13:48:05,752 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_on_value_with_upsert_p4)
2025-10-31 13:48:05,753 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 5899b308-93fd-415d-a67e-30c4e88a14fe, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,756 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 5899b308-93fd-415d-a67e-30c4e88a14fe, ghrun-e45ihybpyu.auto.internal:20918): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,758 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 5899b308-93fd-415d-a67e-30c4e88a14fe, 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=ZjRjNTk2OGUtYjBlYTUxNTYtYmIzNTlkMjctODI4ZGE5MTI=" } } } }
2025-10-31 13:48:05,762 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, fa31fddf-c204-4414-b425-eae5f423b3e6, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,763 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, fa31fddf-c204-4414-b425-eae5f423b3e6, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZjRjNTk2OGUtYjBlYTUxNTYtYmIzNTlkMjctODI4ZGE5MTI=" yql_text: "create table test_lost_update_on_value_with_upsert_p4 (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,795 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, fa31fddf-c204-4414-b425-eae5f423b3e6, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:48:05,798 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 3adc928f-3aef-4dd4-b962-c3832afa7587, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,798 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 3adc928f-3aef-4dd4-b962-c3832afa7587, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZjRjNTk2OGUtYjBlYTUxNTYtYmIzNTlkMjctODI4ZGE5MTI=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_lost_update_on_value_with_upsert_p4 (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,822 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 3adc928f-3aef-4dd4-b962-c3832afa7587, 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,824 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 4dabbd58-b44d-4e49-ba70-3f131d9d0ab1, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,829 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 4dabbd58-b44d-4e49-ba70-3f131d9d0ab1, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZjRjNTk2OGUtYjBlYTUxNTYtYmIzNTlkMjctODI4ZGE5MTI=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value from test_lost_update_on_value_with_upsert_p4 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,866 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 4dabbd58-b44d-4e49-ba70-3f131d9d0ab1, 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: "01k8x8d1b45hhmby3n8fgtast0" } } } } }
2025-10-31 13:48:05,867 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ad80a31d-7047-4a82-bda0-f658c662d688, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,868 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ad80a31d-7047-4a82-bda0-f658c662d688, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZjRjNTk2OGUtYjBlYTUxNTYtYmIzNTlkMjctODI4ZGE5MTI=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value from test_lost_update_on_value_with_upsert_p4 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,893 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ad80a31d-7047-4a82-bda0-f658c662d688, 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: "01k8x8d1bybcvka41jaak1kexw" } } } } }
2025-10-31 13:48:05,895 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ec5b391e-8c06-4ad8-8202-96fee01d1bc4, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,896 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ec5b391e-8c06-4ad8-8202-96fee01d1bc4, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZjRjNTk2OGUtYjBlYTUxNTYtYmIzNTlkMjctODI4ZGE5MTI=" tx_control { tx_id: "01k8x8d1b45hhmby3n8fgtast0" } query { yql_text: " upsert into test_lost_update_on_value_with_upsert_p4 (id, value) VALUES (1, 11);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,916 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ec5b391e-8c06-4ad8-8202-96fee01d1bc4, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8d1b45hhmby3n8fgtast0" } } } } }
2025-10-31 13:48:05,921 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, cd0aea49-3176-41f4-9a46-6101e071cfc0, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,921 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, cd0aea49-3176-41f4-9a46-6101e071cfc0, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZjRjNTk2OGUtYjBlYTUxNTYtYmIzNTlkMjctODI4ZGE5MTI=" tx_id: "01k8x8d1b45hhmby3n8fgtast0" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,930 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, cd0aea49-3176-41f4-9a46-6101e071cfc0, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 13:48:05,931 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 239a5807-afcf-4f3e-b6f8-f7e485190396, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,931 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 239a5807-afcf-4f3e-b6f8-f7e485190396, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZjRjNTk2OGUtYjBlYTUxNTYtYmIzNTlkMjctODI4ZGE5MTI=" tx_control { tx_id: "01k8x8d1bybcvka41jaak1kexw" } query { yql_text: " upsert into test_lost_update_on_value_with_upsert_p4 (id, value) VALUES (1, 12);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,960 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 239a5807-afcf-4f3e-b6f8-f7e485190396, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8d1bybcvka41jaak1kexw" } } } } }
2025-10-31 13:48:05,980 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, cd297c1f-a239-44b7-89f2-f481c9b92a1b, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:05,982 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, cd297c1f-a239-44b7-89f2-f481c9b92a1b, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZjRjNTk2OGUtYjBlYTUxNTYtYmIzNTlkMjctODI4ZGE5MTI=" tx_id: "01k8x8d1bybcvka41jaak1kexw" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:05,985 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, cd297c1f-a239-44b7-89f2-f481c9b92a1b, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_on_value_with_upsert_p4`." issue_code: 2001 severity: 1 issues { message: "Operation is aborting because locks are not valid" issue_code: 2001 severity: 1 } } } }
2025-10-31 13:48:06,025 - INFO - ya.test - pytest_runtest_teardown: Test teardown
