2025-10-31 15:41:12,803 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:12,803 - INFO - ya.test - pytest_runtest_setup: test_lost_update_on_value_with_upsert_p4
2025-10-31 15:41:12,803 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:12,803 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:41:12,804 - 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-10-31 15:41:12,805 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2901d0e8-2171-415f-90ef-57de2388d144, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,805 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2901d0e8-2171-415f-90ef-57de2388d144, ghrun-xldpwfo7ey.auto.internal:6264): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:12,807 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2901d0e8-2171-415f-90ef-57de2388d144, 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=MzI2NTUwZTItMTZjZDNkYTctYTBjNmRkZjAtN2E2MjI1NmE=" } } } }
2025-10-31 15:41:12,808 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, f3531d91-216e-4b64-858c-cc72c319e666, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,808 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, f3531d91-216e-4b64-858c-cc72c319e666, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MzI2NTUwZTItMTZjZDNkYTctYTBjNmRkZjAtN2E2MjI1NmE=" 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-10-31 15:41:12,835 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, f3531d91-216e-4b64-858c-cc72c319e666, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:12,836 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 753fda99-3687-4780-adf3-a181e9bf76e2, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,837 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 753fda99-3687-4780-adf3-a181e9bf76e2, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MzI2NTUwZTItMTZjZDNkYTctYTBjNmRkZjAtN2E2MjI1NmE=" 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-10-31 15:41:12,857 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 753fda99-3687-4780-adf3-a181e9bf76e2, 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,858 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 117f36a1-1add-437c-ae82-2c5a196e1700, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,859 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 117f36a1-1add-437c-ae82-2c5a196e1700, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MzI2NTUwZTItMTZjZDNkYTctYTBjNmRkZjAtN2E2MjI1NmE=" 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-10-31 15:41:12,879 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 117f36a1-1add-437c-ae82-2c5a196e1700, 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: "01k8xew59detvn88pzw6xa4mj3" } } } } }
2025-10-31 15:41:12,880 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, fec094fe-c937-4479-a6ba-8d08b05f1cd8, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,881 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, fec094fe-c937-4479-a6ba-8d08b05f1cd8, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MzI2NTUwZTItMTZjZDNkYTctYTBjNmRkZjAtN2E2MjI1NmE=" 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-10-31 15:41:12,918 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, fec094fe-c937-4479-a6ba-8d08b05f1cd8, 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: "01k8xew5akcv477zzc27557pps" } } } } }
2025-10-31 15:41:12,943 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ea3b49bf-ec0e-4c55-acb7-592e9ac84be1, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,943 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ea3b49bf-ec0e-4c55-acb7-592e9ac84be1, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MzI2NTUwZTItMTZjZDNkYTctYTBjNmRkZjAtN2E2MjI1NmE=" tx_control { tx_id: "01k8xew59detvn88pzw6xa4mj3" } 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-10-31 15:41:12,956 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ea3b49bf-ec0e-4c55-acb7-592e9ac84be1, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew59detvn88pzw6xa4mj3" } } } } }
2025-10-31 15:41:12,975 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, d970144d-f876-45f3-8783-e61c1da08dd7, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,977 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, d970144d-f876-45f3-8783-e61c1da08dd7, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MzI2NTUwZTItMTZjZDNkYTctYTBjNmRkZjAtN2E2MjI1NmE=" tx_id: "01k8xew59detvn88pzw6xa4mj3" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:12,983 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, d970144d-f876-45f3-8783-e61c1da08dd7, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 15:41:12,990 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 68891c4e-0a81-43c4-bb8b-0a538a38f0b2, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:12,990 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 68891c4e-0a81-43c4-bb8b-0a538a38f0b2, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MzI2NTUwZTItMTZjZDNkYTctYTBjNmRkZjAtN2E2MjI1NmE=" tx_control { tx_id: "01k8xew5akcv477zzc27557pps" } 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-10-31 15:41:13,013 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 68891c4e-0a81-43c4-bb8b-0a538a38f0b2, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew5akcv477zzc27557pps" } } } } }
2025-10-31 15:41:13,014 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, e1bad8c4-ec0b-4a99-a8aa-7412351d1b55, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:13,014 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, e1bad8c4-ec0b-4a99-a8aa-7412351d1b55, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MzI2NTUwZTItMTZjZDNkYTctYTBjNmRkZjAtN2E2MjI1NmE=" tx_id: "01k8xew5akcv477zzc27557pps" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:13,018 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, e1bad8c4-ec0b-4a99-a8aa-7412351d1b55, ghrun-xldpwfo7ey.auto.internal:6264): 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 issues { message: "Operation is aborting because locks are not valid" issue_code: 2001 severity: 1 } } } }
2025-10-31 15:41:13,020 - INFO - ya.test - pytest_runtest_teardown: Test teardown
