2025-12-12 10:05:18,856 - INFO - ya.test.2060683  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:18,856 - INFO - ya.test.2060683  - pytest_runtest_setup: test_lost_update_p4
2025-12-12 10:05:18,869 - INFO - ya.test.2060683  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:18,869 - INFO - ya.test.2060683  - pytest_runtest_setup: Test setup
2025-12-12 10:05:18,870 - INFO - ya.test.2060683  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_p4)
2025-12-12 10:05:18,871 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CreateSession, f29aaa3a-5e1a-4990-b733-65ac50069584, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:18,871 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CreateSession, f29aaa3a-5e1a-4990-b733-65ac50069584, ghrun-llwi4fnhxu.auto.internal:27863): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:18,874 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CreateSession, f29aaa3a-5e1a-4990-b733-65ac50069584, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZTM2MWFhN2MtNWVlYzI1M2ItMjhjNTE1OTItOTc4M2ZiNDk=" } } } }
2025-12-12 10:05:18,878 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteSchemeQuery, b7ab3656-0e67-480a-9a97-3d0ed83af8e2, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:18,886 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteSchemeQuery, b7ab3656-0e67-480a-9a97-3d0ed83af8e2, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2MWFhN2MtNWVlYzI1M2ItMjhjNTE1OTItOTc4M2ZiNDk=" 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-12 10:05:18,918 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteSchemeQuery, b7ab3656-0e67-480a-9a97-3d0ed83af8e2, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:18,938 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 1e6103ea-67d9-4c33-8b3a-81f4105b5e36, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:18,949 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 1e6103ea-67d9-4c33-8b3a-81f4105b5e36, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2MWFhN2MtNWVlYzI1M2ItMjhjNTE1OTItOTc4M2ZiNDk=" 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-12 10:05:18,970 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 1e6103ea-67d9-4c33-8b3a-81f4105b5e36, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-12 10:05:18,972 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, e0f57e2d-c758-4749-b8ec-6ab800576255, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:18,972 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, e0f57e2d-c758-4749-b8ec-6ab800576255, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2MWFhN2MtNWVlYzI1M2ItMjhjNTE1OTItOTc4M2ZiNDk=" 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-12 10:05:19,002 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, e0f57e2d-c758-4749-b8ec-6ab800576255, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90b9rqfktxemc2r4f9vbfj" } } } } }
2025-12-12 10:05:19,003 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 39d9e719-ca91-4dc1-ae8b-f31af128a3c2, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:19,003 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 39d9e719-ca91-4dc1-ae8b-f31af128a3c2, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2MWFhN2MtNWVlYzI1M2ItMjhjNTE1OTItOTc4M2ZiNDk=" 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-12 10:05:19,043 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 39d9e719-ca91-4dc1-ae8b-f31af128a3c2, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90b9sz8ph1j52jzt56hhs1" } } } } }
2025-12-12 10:05:19,061 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 32007dc2-14e3-40b3-b6c8-4ed875b5a1d0, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:19,062 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 32007dc2-14e3-40b3-b6c8-4ed875b5a1d0, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2MWFhN2MtNWVlYzI1M2ItMjhjNTE1OTItOTc4M2ZiNDk=" tx_control { tx_id: "01kc90b9rqfktxemc2r4f9vbfj" } 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-12 10:05:19,089 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 32007dc2-14e3-40b3-b6c8-4ed875b5a1d0, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90b9rqfktxemc2r4f9vbfj" } } } } }
2025-12-12 10:05:19,101 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, d69a1424-6ae1-44fa-af0d-45cbd7629491, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:19,107 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, d69a1424-6ae1-44fa-af0d-45cbd7629491, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2MWFhN2MtNWVlYzI1M2ItMjhjNTE1OTItOTc4M2ZiNDk=" tx_control { tx_id: "01kc90b9sz8ph1j52jzt56hhs1" } 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-12 10:05:19,131 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, d69a1424-6ae1-44fa-af0d-45cbd7629491, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90b9sz8ph1j52jzt56hhs1" } } } } }
2025-12-12 10:05:19,141 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, 2f937eaa-c1d8-4e02-8311-331ad1a09e3d, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:19,142 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, 2f937eaa-c1d8-4e02-8311-331ad1a09e3d, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2MWFhN2MtNWVlYzI1M2ItMjhjNTE1OTItOTc4M2ZiNDk=" tx_id: "01kc90b9rqfktxemc2r4f9vbfj" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:19,152 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, 2f937eaa-c1d8-4e02-8311-331ad1a09e3d, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-12 10:05:19,153 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, eb946a91-124c-41b5-aabd-fb04ec889b36, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:19,153 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, eb946a91-124c-41b5-aabd-fb04ec889b36, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2MWFhN2MtNWVlYzI1M2ItMjhjNTE1OTItOTc4M2ZiNDk=" tx_id: "01kc90b9sz8ph1j52jzt56hhs1" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:19,162 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, eb946a91-124c-41b5-aabd-fb04ec889b36, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_p4`" issue_code: 2001 severity: 1 } } }
2025-12-12 10:05:19,165 - INFO - ya.test.2060683  - pytest_runtest_teardown: Test teardown
