2025-11-26 18:41:41,086 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:41,086 - INFO - ya.test.2332018  - pytest_runtest_setup: test_lost_update_on_value_with_upsert_p4
2025-11-26 18:41:41,086 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:41,086 - INFO - ya.test.2332018  - pytest_runtest_setup: Test setup
2025-11-26 18:41:41,088 - INFO - ya.test.2332018  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_on_value_with_upsert_p4)
2025-11-26 18:41:41,088 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CreateSession, 4c6796a2-d68e-4cd0-af0a-b2494a6bce5a, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,090 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CreateSession, 4c6796a2-d68e-4cd0-af0a-b2494a6bce5a, ghrun-ihttfokzrq.auto.internal:8570): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:41,092 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CreateSession, 4c6796a2-d68e-4cd0-af0a-b2494a6bce5a, 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=ZDFmNjQ4NmQtZjhlODJiMGYtMWE5OTgxZTYtYjQ5N2EwMjM=" } } } }
2025-11-26 18:41:41,093 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteSchemeQuery, 15233ee4-c322-4b6b-9e5f-deb90ef93c2a, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,094 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteSchemeQuery, 15233ee4-c322-4b6b-9e5f-deb90ef93c2a, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDFmNjQ4NmQtZjhlODJiMGYtMWE5OTgxZTYtYjQ5N2EwMjM=" 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-11-26 18:41:41,120 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteSchemeQuery, 15233ee4-c322-4b6b-9e5f-deb90ef93c2a, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:41:41,121 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 76e83577-262d-4783-9838-c3621fbca41e, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,122 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 76e83577-262d-4783-9838-c3621fbca41e, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDFmNjQ4NmQtZjhlODJiMGYtMWE5OTgxZTYtYjQ5N2EwMjM=" 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-11-26 18:41:41,141 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 76e83577-262d-4783-9838-c3621fbca41e, 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:41,142 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 5df94fb6-9028-4df8-94b9-1cfbba00a82d, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,142 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 5df94fb6-9028-4df8-94b9-1cfbba00a82d, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDFmNjQ4NmQtZjhlODJiMGYtMWE5OTgxZTYtYjQ5N2EwMjM=" 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-11-26 18:41:41,165 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 5df94fb6-9028-4df8-94b9-1cfbba00a82d, 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 } } } } 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: "01kb0qh9s960md6y74h3zrmpnr" } } } } }
2025-11-26 18:41:41,166 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, dfca9076-8707-44cc-a6de-57c01f6e366f, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,166 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, dfca9076-8707-44cc-a6de-57c01f6e366f, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDFmNjQ4NmQtZjhlODJiMGYtMWE5OTgxZTYtYjQ5N2EwMjM=" 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-11-26 18:41:41,188 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, dfca9076-8707-44cc-a6de-57c01f6e366f, 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 } } } } 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: "01kb0qh9t0d2c2akqkbttew84d" } } } } }
2025-11-26 18:41:41,189 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, f03666ad-b5e6-4a66-a377-62919c0e74c3, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,190 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, f03666ad-b5e6-4a66-a377-62919c0e74c3, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDFmNjQ4NmQtZjhlODJiMGYtMWE5OTgxZTYtYjQ5N2EwMjM=" tx_control { tx_id: "01kb0qh9s960md6y74h3zrmpnr" } 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-11-26 18:41:41,203 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, f03666ad-b5e6-4a66-a377-62919c0e74c3, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh9s960md6y74h3zrmpnr" } } } } }
2025-11-26 18:41:41,204 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, 226aac56-da29-4638-a946-794ad5d069af, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,204 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, 226aac56-da29-4638-a946-794ad5d069af, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDFmNjQ4NmQtZjhlODJiMGYtMWE5OTgxZTYtYjQ5N2EwMjM=" tx_id: "01kb0qh9s960md6y74h3zrmpnr" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:41,212 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, 226aac56-da29-4638-a946-794ad5d069af, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-11-26 18:41:41,213 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 9f7f1d04-6118-4ebf-9e10-ff2413ed9e0e, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,214 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 9f7f1d04-6118-4ebf-9e10-ff2413ed9e0e, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDFmNjQ4NmQtZjhlODJiMGYtMWE5OTgxZTYtYjQ5N2EwMjM=" tx_control { tx_id: "01kb0qh9t0d2c2akqkbttew84d" } 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-11-26 18:41:41,229 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 9f7f1d04-6118-4ebf-9e10-ff2413ed9e0e, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh9t0d2c2akqkbttew84d" } } } } }
2025-11-26 18:41:41,230 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, 7df5e00c-715b-49cf-b971-b33d7ef1cc12, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,230 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, 7df5e00c-715b-49cf-b971-b33d7ef1cc12, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDFmNjQ4NmQtZjhlODJiMGYtMWE5OTgxZTYtYjQ5N2EwMjM=" tx_id: "01kb0qh9t0d2c2akqkbttew84d" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:41,235 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, 7df5e00c-715b-49cf-b971-b33d7ef1cc12, ghrun-ihttfokzrq.auto.internal:8570): 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-11-26 18:41:41,237 - INFO - ya.test.2332018  - pytest_runtest_teardown: Test teardown
