2025-11-26 18:41:40,871 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:40,872 - INFO - ya.test.2332018  - pytest_runtest_setup: test_lost_update_on_value_p4
2025-11-26 18:41:40,872 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:40,872 - INFO - ya.test.2332018  - pytest_runtest_setup: Test setup
2025-11-26 18:41:40,873 - INFO - ya.test.2332018  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_on_value_p4)
2025-11-26 18:41:40,874 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CreateSession, fb9bc560-d649-4987-9a7c-ea0bb5f27707, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,874 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CreateSession, fb9bc560-d649-4987-9a7c-ea0bb5f27707, ghrun-ihttfokzrq.auto.internal:8570): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,877 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CreateSession, fb9bc560-d649-4987-9a7c-ea0bb5f27707, 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=NmI1NTNkMzEtOGRkOTBhNTctNjE2M2RhM2UtY2JmMzcxNzc=" } } } }
2025-11-26 18:41:40,877 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteSchemeQuery, 3391e68f-0c29-42b6-a304-533f2124c158, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,878 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteSchemeQuery, 3391e68f-0c29-42b6-a304-533f2124c158, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NmI1NTNkMzEtOGRkOTBhNTctNjE2M2RhM2UtY2JmMzcxNzc=" yql_text: "create table test_lost_update_on_value_p4 (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,928 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteSchemeQuery, 3391e68f-0c29-42b6-a304-533f2124c158, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:41:40,929 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 68ca82e4-4851-4665-b41f-c19be998b59f, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,930 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 68ca82e4-4851-4665-b41f-c19be998b59f, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NmI1NTNkMzEtOGRkOTBhNTctNjE2M2RhM2UtY2JmMzcxNzc=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_lost_update_on_value_p4 (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,946 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 68ca82e4-4851-4665-b41f-c19be998b59f, 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,947 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 29492062-87db-4dd8-a77e-5832cd7e9e8b, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,948 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 29492062-87db-4dd8-a77e-5832cd7e9e8b, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NmI1NTNkMzEtOGRkOTBhNTctNjE2M2RhM2UtY2JmMzcxNzc=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value from test_lost_update_on_value_p4 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,971 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 29492062-87db-4dd8-a77e-5832cd7e9e8b, 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: "01kb0qh9k87yb2t3wha29d7dr6" } } } } }
2025-11-26 18:41:40,972 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 27a8812e-3c47-486d-a56d-3f8be6e50ed3, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,973 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 27a8812e-3c47-486d-a56d-3f8be6e50ed3, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NmI1NTNkMzEtOGRkOTBhNTctNjE2M2RhM2UtY2JmMzcxNzc=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value from test_lost_update_on_value_p4 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,992 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 27a8812e-3c47-486d-a56d-3f8be6e50ed3, 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: "01kb0qh9kxfk7z74x2c1zarczt" } } } } }
2025-11-26 18:41:40,993 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, c0a60a3f-0ed3-4665-857f-9ec314a5b59d, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,995 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, c0a60a3f-0ed3-4665-857f-9ec314a5b59d, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NmI1NTNkMzEtOGRkOTBhNTctNjE2M2RhM2UtY2JmMzcxNzc=" tx_control { tx_id: "01kb0qh9k87yb2t3wha29d7dr6" } query { yql_text: " update test_lost_update_on_value_p4 set value = 11 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:41,027 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, c0a60a3f-0ed3-4665-857f-9ec314a5b59d, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh9k87yb2t3wha29d7dr6" } } } } }
2025-11-26 18:41:41,028 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, 1408b59a-5bf6-4eac-97f7-3f189054a060, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,029 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, 1408b59a-5bf6-4eac-97f7-3f189054a060, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NmI1NTNkMzEtOGRkOTBhNTctNjE2M2RhM2UtY2JmMzcxNzc=" tx_id: "01kb0qh9k87yb2t3wha29d7dr6" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:41,047 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, 1408b59a-5bf6-4eac-97f7-3f189054a060, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-11-26 18:41:41,048 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 70c5cfd5-87ed-4d73-9ed9-28f52c90d278, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,049 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 70c5cfd5-87ed-4d73-9ed9-28f52c90d278, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NmI1NTNkMzEtOGRkOTBhNTctNjE2M2RhM2UtY2JmMzcxNzc=" tx_control { tx_id: "01kb0qh9kxfk7z74x2c1zarczt" } query { yql_text: " update test_lost_update_on_value_p4 set value = 12 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:41,076 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 70c5cfd5-87ed-4d73-9ed9-28f52c90d278, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh9kxfk7z74x2c1zarczt" } } } } }
2025-11-26 18:41:41,076 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, 31be0f10-1d55-4b02-b25b-b2012c944f4e, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,077 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, 31be0f10-1d55-4b02-b25b-b2012c944f4e, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NmI1NTNkMzEtOGRkOTBhNTctNjE2M2RhM2UtY2JmMzcxNzc=" tx_id: "01kb0qh9kxfk7z74x2c1zarczt" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:41,080 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, 31be0f10-1d55-4b02-b25b-b2012c944f4e, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_on_value_p4`" issue_code: 2001 severity: 1 } } }
2025-11-26 18:41:41,082 - INFO - ya.test.2332018  - pytest_runtest_teardown: Test teardown
