2025-12-08 23:27:56,477 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:56,511 - INFO - ya.test - pytest_runtest_setup: test_lost_update_on_value_with_upsert_p4
2025-12-08 23:27:56,511 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:56,517 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-08 23:27:56,565 - 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-12-08 23:27:56,582 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 3bf0880c-7f24-40f7-86dd-2c56e9f3a392, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,595 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 3bf0880c-7f24-40f7-86dd-2c56e9f3a392, ghrun-sfoiezpm3a.auto.internal:1948): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:56,602 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 3bf0880c-7f24-40f7-86dd-2c56e9f3a392, 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=YTA1NGUxYWQtOTRhMWI4MWMtODJlZDI3OGYtODJjMDAwMjU=" } } } }
2025-12-08 23:27:56,603 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 0a61e768-8a02-4f27-83d2-6b96b5135343, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,603 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 0a61e768-8a02-4f27-83d2-6b96b5135343, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=YTA1NGUxYWQtOTRhMWI4MWMtODJlZDI3OGYtODJjMDAwMjU=" 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-12-08 23:27:56,651 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 0a61e768-8a02-4f27-83d2-6b96b5135343, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:27:56,660 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ff58f290-a67c-47bf-8450-82f52393b364, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,660 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ff58f290-a67c-47bf-8450-82f52393b364, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=YTA1NGUxYWQtOTRhMWI4MWMtODJlZDI3OGYtODJjMDAwMjU=" 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-12-08 23:27:56,690 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ff58f290-a67c-47bf-8450-82f52393b364, 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:56,706 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ea95f4de-6637-4570-ad01-daa3375c8ab9, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,750 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ea95f4de-6637-4570-ad01-daa3375c8ab9, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=YTA1NGUxYWQtOTRhMWI4MWMtODJlZDI3OGYtODJjMDAwMjU=" 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-12-08 23:27:56,821 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ea95f4de-6637-4570-ad01-daa3375c8ab9, 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 } } } } 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: "01kc04p2wgczzc7t4md414h081" } } } } }
2025-12-08 23:27:56,902 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 71a6b387-39f1-4ce7-9c47-564e199540af, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,913 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 71a6b387-39f1-4ce7-9c47-564e199540af, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=YTA1NGUxYWQtOTRhMWI4MWMtODJlZDI3OGYtODJjMDAwMjU=" 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-12-08 23:27:56,983 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 71a6b387-39f1-4ce7-9c47-564e199540af, 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 } } } } 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: "01kc04p31eespdjk0dtyxc6rpn" } } } } }
2025-12-08 23:27:56,984 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 36c51866-bd70-4458-9b46-b55a880f38d1, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,987 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 36c51866-bd70-4458-9b46-b55a880f38d1, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=YTA1NGUxYWQtOTRhMWI4MWMtODJlZDI3OGYtODJjMDAwMjU=" tx_control { tx_id: "01kc04p2wgczzc7t4md414h081" } 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-12-08 23:27:57,066 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 36c51866-bd70-4458-9b46-b55a880f38d1, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p2wgczzc7t4md414h081" } } } } }
2025-12-08 23:27:57,074 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 4922407d-b9dc-41f4-b001-afff1941e687, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:57,074 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 4922407d-b9dc-41f4-b001-afff1941e687, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=YTA1NGUxYWQtOTRhMWI4MWMtODJlZDI3OGYtODJjMDAwMjU=" tx_id: "01kc04p2wgczzc7t4md414h081" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:57,082 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 4922407d-b9dc-41f4-b001-afff1941e687, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-08 23:27:57,090 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a46721b1-01da-43ba-a40b-931d803625a2, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:57,093 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a46721b1-01da-43ba-a40b-931d803625a2, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=YTA1NGUxYWQtOTRhMWI4MWMtODJlZDI3OGYtODJjMDAwMjU=" tx_control { tx_id: "01kc04p31eespdjk0dtyxc6rpn" } 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-12-08 23:27:57,120 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a46721b1-01da-43ba-a40b-931d803625a2, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p31eespdjk0dtyxc6rpn" } } } } }
2025-12-08 23:27:57,121 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 2ccf3f81-197c-4ce7-859f-5dbef83ced07, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:57,141 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 2ccf3f81-197c-4ce7-859f-5dbef83ced07, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=YTA1NGUxYWQtOTRhMWI4MWMtODJlZDI3OGYtODJjMDAwMjU=" tx_id: "01kc04p31eespdjk0dtyxc6rpn" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:57,161 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 2ccf3f81-197c-4ce7-859f-5dbef83ced07, ghrun-sfoiezpm3a.auto.internal:1948): 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 } } }
2025-12-08 23:27:57,182 - INFO - ya.test - pytest_runtest_teardown: Test teardown
