2025-10-31 15:41:12,434 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:12,435 - INFO - ya.test - pytest_runtest_setup: test_lost_update_on_value_p4
2025-10-31 15:41:12,436 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:12,436 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:41:12,439 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_on_value_p4)
2025-10-31 15:41:12,455 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, b8a3d300-6e07-4ae0-aba4-355fc88f49f4, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,455 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, b8a3d300-6e07-4ae0-aba4-355fc88f49f4, ghrun-xldpwfo7ey.auto.internal:6264): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:12,458 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, b8a3d300-6e07-4ae0-aba4-355fc88f49f4, 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=NDlkMGFjYzUtOTQyOWUxMDQtM2M1MzY0M2QtYjc3YTI1ZDA=" } } } }
2025-10-31 15:41:12,475 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 3968c035-57c2-413a-81fe-70ddd480d6d2, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,533 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 3968c035-57c2-413a-81fe-70ddd480d6d2, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NDlkMGFjYzUtOTQyOWUxMDQtM2M1MzY0M2QtYjc3YTI1ZDA=" 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-10-31 15:41:12,556 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 3968c035-57c2-413a-81fe-70ddd480d6d2, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:12,563 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a6b043aa-1cd5-4630-b822-2b7e9ec0579c, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,564 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a6b043aa-1cd5-4630-b822-2b7e9ec0579c, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NDlkMGFjYzUtOTQyOWUxMDQtM2M1MzY0M2QtYjc3YTI1ZDA=" 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-10-31 15:41:12,617 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a6b043aa-1cd5-4630-b822-2b7e9ec0579c, 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,623 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 31262425-f604-49bb-adba-4f74dac4803c, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,624 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 31262425-f604-49bb-adba-4f74dac4803c, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NDlkMGFjYzUtOTQyOWUxMDQtM2M1MzY0M2QtYjc3YTI1ZDA=" 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-10-31 15:41:12,650 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 31262425-f604-49bb-adba-4f74dac4803c, 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 } } } } 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: "01k8xew528a5hvbshscj7acg8g" } } } } }
2025-10-31 15:41:12,654 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, eba1ca28-a16a-4c84-9597-82b8d183e988, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,668 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, eba1ca28-a16a-4c84-9597-82b8d183e988, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NDlkMGFjYzUtOTQyOWUxMDQtM2M1MzY0M2QtYjc3YTI1ZDA=" 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-10-31 15:41:12,706 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, eba1ca28-a16a-4c84-9597-82b8d183e988, 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 } } } } 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: "01k8xew53z8x4rgq8gtre0g6vk" } } } } }
2025-10-31 15:41:12,720 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, cd7d09cd-c278-4b68-8744-60a8f69579c1, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,722 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, cd7d09cd-c278-4b68-8744-60a8f69579c1, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NDlkMGFjYzUtOTQyOWUxMDQtM2M1MzY0M2QtYjc3YTI1ZDA=" tx_control { tx_id: "01k8xew528a5hvbshscj7acg8g" } 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-10-31 15:41:12,750 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, cd7d09cd-c278-4b68-8744-60a8f69579c1, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew528a5hvbshscj7acg8g" } } } } }
2025-10-31 15:41:12,751 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 3b241544-076f-4bdd-9d0c-1fc9da00ca76, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,752 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 3b241544-076f-4bdd-9d0c-1fc9da00ca76, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NDlkMGFjYzUtOTQyOWUxMDQtM2M1MzY0M2QtYjc3YTI1ZDA=" tx_id: "01k8xew528a5hvbshscj7acg8g" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:12,757 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 3b241544-076f-4bdd-9d0c-1fc9da00ca76, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 15:41:12,758 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0bc44db8-cc52-4459-9cb9-c7fb5dbe43e3, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,758 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0bc44db8-cc52-4459-9cb9-c7fb5dbe43e3, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NDlkMGFjYzUtOTQyOWUxMDQtM2M1MzY0M2QtYjc3YTI1ZDA=" tx_control { tx_id: "01k8xew53z8x4rgq8gtre0g6vk" } 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-10-31 15:41:12,783 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0bc44db8-cc52-4459-9cb9-c7fb5dbe43e3, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew53z8x4rgq8gtre0g6vk" } } } } }
2025-10-31 15:41:12,794 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, c12ea978-c82b-4dab-b245-21d821007767, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,794 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, c12ea978-c82b-4dab-b245-21d821007767, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=NDlkMGFjYzUtOTQyOWUxMDQtM2M1MzY0M2QtYjc3YTI1ZDA=" tx_id: "01k8xew53z8x4rgq8gtre0g6vk" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:12,798 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, c12ea978-c82b-4dab-b245-21d821007767, ghrun-xldpwfo7ey.auto.internal:6264): 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-10-31 15:41:12,800 - INFO - ya.test - pytest_runtest_teardown: Test teardown
