2025-12-08 23:27:55,998 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:56,012 - INFO - ya.test - pytest_runtest_setup: test_lost_update_on_value_p4
2025-12-08 23:27:56,012 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:56,012 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-08 23:27:56,024 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_lost_update_on_value_p4)
2025-12-08 23:27:56,039 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8ab42ea8-1e40-4a06-9677-3d11174dd615, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,040 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8ab42ea8-1e40-4a06-9677-3d11174dd615, ghrun-sfoiezpm3a.auto.internal:1948): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:56,043 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8ab42ea8-1e40-4a06-9677-3d11174dd615, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OGE2Y2U2N2YtMmJlMTdiMC0yNTMwYjNjZS02ZDJjNDNk" } } } }
2025-12-08 23:27:56,090 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, c3057881-8717-4dfc-b932-95d1a2e2bb73, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,091 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, c3057881-8717-4dfc-b932-95d1a2e2bb73, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=OGE2Y2U2N2YtMmJlMTdiMC0yNTMwYjNjZS02ZDJjNDNk" 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-12-08 23:27:56,130 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, c3057881-8717-4dfc-b932-95d1a2e2bb73, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:27:56,130 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c3d76174-52e2-47a0-84aa-c40be677a060, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,132 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c3d76174-52e2-47a0-84aa-c40be677a060, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=OGE2Y2U2N2YtMmJlMTdiMC0yNTMwYjNjZS02ZDJjNDNk" 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-12-08 23:27:56,168 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c3d76174-52e2-47a0-84aa-c40be677a060, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:27:56,170 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 29dd42d1-5bc4-4de7-acf6-7435f054bc1b, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,180 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 29dd42d1-5bc4-4de7-acf6-7435f054bc1b, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=OGE2Y2U2N2YtMmJlMTdiMC0yNTMwYjNjZS02ZDJjNDNk" 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-12-08 23:27:56,206 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 29dd42d1-5bc4-4de7-acf6-7435f054bc1b, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04p29a1gstp7fhd04sz00d" } } } } }
2025-12-08 23:27:56,214 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 2d486a51-be56-439b-b333-90f841c09869, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,228 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 2d486a51-be56-439b-b333-90f841c09869, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=OGE2Y2U2N2YtMmJlMTdiMC0yNTMwYjNjZS02ZDJjNDNk" 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-12-08 23:27:56,266 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 2d486a51-be56-439b-b333-90f841c09869, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04p2b5bmqawd7jercwnr0s" } } } } }
2025-12-08 23:27:56,267 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0845944b-fff5-4b82-be38-a4a55c656db4, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,296 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0845944b-fff5-4b82-be38-a4a55c656db4, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=OGE2Y2U2N2YtMmJlMTdiMC0yNTMwYjNjZS02ZDJjNDNk" tx_control { tx_id: "01kc04p29a1gstp7fhd04sz00d" } 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-12-08 23:27:56,338 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0845944b-fff5-4b82-be38-a4a55c656db4, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p29a1gstp7fhd04sz00d" } } } } }
2025-12-08 23:27:56,353 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, b14914f1-6852-4936-b07b-40ed48c49dad, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,363 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, b14914f1-6852-4936-b07b-40ed48c49dad, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=OGE2Y2U2N2YtMmJlMTdiMC0yNTMwYjNjZS02ZDJjNDNk" tx_id: "01kc04p29a1gstp7fhd04sz00d" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:56,395 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, b14914f1-6852-4936-b07b-40ed48c49dad, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-08 23:27:56,396 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 20d316f8-675f-4d54-917a-864e54fc88ff, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:56,396 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 20d316f8-675f-4d54-917a-864e54fc88ff, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=OGE2Y2U2N2YtMmJlMTdiMC0yNTMwYjNjZS02ZDJjNDNk" tx_control { tx_id: "01kc04p2b5bmqawd7jercwnr0s" } 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-12-08 23:27:56,440 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 20d316f8-675f-4d54-917a-864e54fc88ff, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_lost_update_on_value_p4`" issue_code: 2001 severity: 1 } issues { message: "tx has deferred effects, but locks are broken" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p2b5bmqawd7jercwnr0s" } } } } }
2025-12-08 23:27:56,447 - INFO - ya.test - pytest_runtest_teardown: Test teardown
