2025-10-31 15:41:12,184 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:12,184 - INFO - ya.test - pytest_runtest_setup: test_lost_update_p4
2025-10-31 15:41:12,184 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:12,185 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:41:12,186 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_p4)
2025-10-31 15:41:12,186 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d94d495d-035a-4b9f-90b9-fea372aa4122, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,187 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d94d495d-035a-4b9f-90b9-fea372aa4122, ghrun-xldpwfo7ey.auto.internal:6264): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:12,189 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d94d495d-035a-4b9f-90b9-fea372aa4122, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NWRmMmVlMGUtZTBiZDYyN2ItZWQwZjc4OTctNjAyNDUzZGY=" } } } }
2025-10-31 15:41:12,190 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 7b6aee52-1f69-4733-b351-fc779103b558, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,190 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 7b6aee52-1f69-4733-b351-fc779103b558, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NWRmMmVlMGUtZTBiZDYyN2ItZWQwZjc4OTctNjAyNDUzZGY=" 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-10-31 15:41:12,229 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 7b6aee52-1f69-4733-b351-fc779103b558, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:12,230 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 611a8579-571b-4a15-9a97-6218eabd48e7, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,231 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 611a8579-571b-4a15-9a97-6218eabd48e7, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NWRmMmVlMGUtZTBiZDYyN2ItZWQwZjc4OTctNjAyNDUzZGY=" 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-10-31 15:41:12,253 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 611a8579-571b-4a15-9a97-6218eabd48e7, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 15:41:12,257 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 9af26cbc-bf6c-457d-bb9c-2de882ac7cab, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,259 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 9af26cbc-bf6c-457d-bb9c-2de882ac7cab, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NWRmMmVlMGUtZTBiZDYyN2ItZWQwZjc4OTctNjAyNDUzZGY=" 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-10-31 15:41:12,281 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 9af26cbc-bf6c-457d-bb9c-2de882ac7cab, ghrun-xldpwfo7ey.auto.internal:6264): 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: "01k8xew4ppd6zzk7cn74k3pdcx" } } } } }
2025-10-31 15:41:12,282 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, d58eccb6-8e36-44f6-aa76-3e1b4883ec7e, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,283 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, d58eccb6-8e36-44f6-aa76-3e1b4883ec7e, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NWRmMmVlMGUtZTBiZDYyN2ItZWQwZjc4OTctNjAyNDUzZGY=" 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-10-31 15:41:12,306 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, d58eccb6-8e36-44f6-aa76-3e1b4883ec7e, ghrun-xldpwfo7ey.auto.internal:6264): 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: "01k8xew4qf7dmgsxtp8rqhn0p7" } } } } }
2025-10-31 15:41:12,307 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a85bdbfe-6d1d-4af1-973a-ffc97c27cb3a, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,307 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a85bdbfe-6d1d-4af1-973a-ffc97c27cb3a, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NWRmMmVlMGUtZTBiZDYyN2ItZWQwZjc4OTctNjAyNDUzZGY=" tx_control { tx_id: "01k8xew4ppd6zzk7cn74k3pdcx" } 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-10-31 15:41:12,334 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a85bdbfe-6d1d-4af1-973a-ffc97c27cb3a, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew4ppd6zzk7cn74k3pdcx" } } } } }
2025-10-31 15:41:12,335 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, cfa58320-9947-4bc4-ae94-0adffbabee90, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,335 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, cfa58320-9947-4bc4-ae94-0adffbabee90, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NWRmMmVlMGUtZTBiZDYyN2ItZWQwZjc4OTctNjAyNDUzZGY=" tx_control { tx_id: "01k8xew4qf7dmgsxtp8rqhn0p7" } 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-10-31 15:41:12,389 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, cfa58320-9947-4bc4-ae94-0adffbabee90, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew4qf7dmgsxtp8rqhn0p7" } } } } }
2025-10-31 15:41:12,390 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, da0f130f-137a-427e-8e6f-1ac3ab0ed0a6, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,390 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, da0f130f-137a-427e-8e6f-1ac3ab0ed0a6, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NWRmMmVlMGUtZTBiZDYyN2ItZWQwZjc4OTctNjAyNDUzZGY=" tx_id: "01k8xew4ppd6zzk7cn74k3pdcx" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:12,396 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, da0f130f-137a-427e-8e6f-1ac3ab0ed0a6, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 15:41:12,397 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 7fdd597a-efa7-42eb-bd31-deeb6c8d1df0, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,397 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 7fdd597a-efa7-42eb-bd31-deeb6c8d1df0, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NWRmMmVlMGUtZTBiZDYyN2ItZWQwZjc4OTctNjAyNDUzZGY=" tx_id: "01k8xew4qf7dmgsxtp8rqhn0p7" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:12,400 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 7fdd597a-efa7-42eb-bd31-deeb6c8d1df0, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_p4`" issue_code: 2001 severity: 1 } } }
2025-10-31 15:41:12,412 - INFO - ya.test - pytest_runtest_teardown: Test teardown
