2025-10-31 13:48:04,180 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:04,186 - INFO - ya.test - pytest_runtest_setup: test_prevents_observed_transaction_vanishes_otv
2025-10-31 13:48:04,186 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:04,186 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 13:48:04,187 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_observed_transaction_vanishes_otv)
2025-10-31 13:48:04,189 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8bfe847d-5c96-4f73-a297-12f4ff6d3d15, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,189 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8bfe847d-5c96-4f73-a297-12f4ff6d3d15, ghrun-e45ihybpyu.auto.internal:20918): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,192 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8bfe847d-5c96-4f73-a297-12f4ff6d3d15, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" } } } }
2025-10-31 13:48:04,192 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, db4192f4-33ec-4d77-912c-9ee9dc235e04, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,193 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, db4192f4-33ec-4d77-912c-9ee9dc235e04, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" yql_text: "create table test_prevents_observed_transaction_vanishes_otv (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,225 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, db4192f4-33ec-4d77-912c-9ee9dc235e04, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:48:04,226 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7fa8fd1c-3204-4ebb-8ae1-f9b4443cc8de, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,226 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7fa8fd1c-3204-4ebb-8ae1-f9b4443cc8de, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_prevents_observed_transaction_vanishes_otv (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,259 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7fa8fd1c-3204-4ebb-8ae1-f9b4443cc8de, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 13:48:04,260 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 102e1930-c5ab-467f-9bb2-2879799030f8, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,269 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 102e1930-c5ab-467f-9bb2-2879799030f8, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select * from test_prevents_observed_transaction_vanishes_otv where id=1 or id=2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,328 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 102e1930-c5ab-467f-9bb2-2879799030f8, ghrun-e45ihybpyu.auto.internal:20918): 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 } } rows { items { int32_value: 2 } items { int32_value: 20 } } format: FORMAT_VALUE } tx_meta { id: "01k8x8czv47t4b0756sajbvxm0" } } } } }
2025-10-31 13:48:04,332 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 033e771f-e8bb-47be-a43c-ed4480e45152, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,333 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 033e771f-e8bb-47be-a43c-ed4480e45152, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { tx_id: "01k8x8czv47t4b0756sajbvxm0" } query { yql_text: " upsert into test_prevents_observed_transaction_vanishes_otv (id, value) values (1, 11);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,346 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 033e771f-e8bb-47be-a43c-ed4480e45152, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8czv47t4b0756sajbvxm0" } } } } }
2025-10-31 13:48:04,362 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 178ee6e9-0191-41f7-9b0b-f60da2e1b18f, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,363 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 178ee6e9-0191-41f7-9b0b-f60da2e1b18f, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { tx_id: "01k8x8czv47t4b0756sajbvxm0" } query { yql_text: " upsert into test_prevents_observed_transaction_vanishes_otv (id, value) values (2, 19);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,384 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 178ee6e9-0191-41f7-9b0b-f60da2e1b18f, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8czv47t4b0756sajbvxm0" } } } } }
2025-10-31 13:48:04,385 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 384aeea3-8fb9-4c1a-9e5f-1b5f8a205912, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,404 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 384aeea3-8fb9-4c1a-9e5f-1b5f8a205912, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select * from test_prevents_observed_transaction_vanishes_otv where id=1" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,425 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 384aeea3-8fb9-4c1a-9e5f-1b5f8a205912, ghrun-e45ihybpyu.auto.internal:20918): 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: "01k8x8czy5ee9qz8ajxh3ftbkn" } } } } }
2025-10-31 13:48:04,440 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 93dd9778-26e6-46be-bcd9-27276724fffc, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,447 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 93dd9778-26e6-46be-bcd9-27276724fffc, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { tx_id: "01k8x8czy5ee9qz8ajxh3ftbkn" } query { yql_text: " upsert into test_prevents_observed_transaction_vanishes_otv (id, value) values (1, 12);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,464 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 93dd9778-26e6-46be-bcd9-27276724fffc, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8czy5ee9qz8ajxh3ftbkn" } } } } }
2025-10-31 13:48:04,476 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, f24d9d43-6de7-4d57-81db-d55fa26ebe47, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,477 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, f24d9d43-6de7-4d57-81db-d55fa26ebe47, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_id: "01k8x8czv47t4b0756sajbvxm0" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,492 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, f24d9d43-6de7-4d57-81db-d55fa26ebe47, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 13:48:04,492 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b2f3e645-12c4-4223-a621-f31578f452a6, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,493 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b2f3e645-12c4-4223-a621-f31578f452a6, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select value from test_prevents_observed_transaction_vanishes_otv where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,529 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b2f3e645-12c4-4223-a621-f31578f452a6, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 11 } } format: FORMAT_VALUE } tx_meta { id: "01k8x8d01bbg757bg5d1rqvepw" } } } } }
2025-10-31 13:48:04,530 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c5df031b-4f42-4be2-b6b1-1b41c15a7c93, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,540 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c5df031b-4f42-4be2-b6b1-1b41c15a7c93, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { tx_id: "01k8x8czy5ee9qz8ajxh3ftbkn" } query { yql_text: " upsert into test_prevents_observed_transaction_vanishes_otv (id, value) values (2, 18);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,567 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c5df031b-4f42-4be2-b6b1-1b41c15a7c93, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8czy5ee9qz8ajxh3ftbkn" } } } } }
2025-10-31 13:48:04,569 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 2b5bc73a-fcad-4884-ad0c-5b7a5c5a491c, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,569 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 2b5bc73a-fcad-4884-ad0c-5b7a5c5a491c, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_id: "01k8x8czy5ee9qz8ajxh3ftbkn" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,578 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 2b5bc73a-fcad-4884-ad0c-5b7a5c5a491c, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_prevents_observed_transaction_vanishes_otv`." issue_code: 2001 severity: 1 issues { message: "Operation is aborting because locks are not valid" issue_code: 2001 severity: 1 } } } }
2025-10-31 13:48:04,585 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b3277b46-b210-4826-93d1-1df39ed2b0ae, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,585 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b3277b46-b210-4826-93d1-1df39ed2b0ae, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { tx_id: "01k8x8d01bbg757bg5d1rqvepw" } query { yql_text: " select value from test_prevents_observed_transaction_vanishes_otv where id = 2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,606 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b3277b46-b210-4826-93d1-1df39ed2b0ae, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 19 } } format: FORMAT_VALUE } tx_meta { id: "01k8x8d01bbg757bg5d1rqvepw" } } } } }
2025-10-31 13:48:04,606 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ff3d5a72-9b8b-429a-9604-cb34244a9a13, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,607 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ff3d5a72-9b8b-429a-9604-cb34244a9a13, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { tx_id: "01k8x8d01bbg757bg5d1rqvepw" } query { yql_text: " select value from test_prevents_observed_transaction_vanishes_otv where id = 2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,632 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ff3d5a72-9b8b-429a-9604-cb34244a9a13, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 19 } } format: FORMAT_VALUE } tx_meta { id: "01k8x8d01bbg757bg5d1rqvepw" } } } } }
2025-10-31 13:48:04,633 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 74606498-e7fd-4737-b4a0-c5e6e2cc2642, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,634 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 74606498-e7fd-4737-b4a0-c5e6e2cc2642, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_control { tx_id: "01k8x8d01bbg757bg5d1rqvepw" } query { yql_text: " select value from test_prevents_observed_transaction_vanishes_otv where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,655 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 74606498-e7fd-4737-b4a0-c5e6e2cc2642, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 11 } } format: FORMAT_VALUE } tx_meta { id: "01k8x8d01bbg757bg5d1rqvepw" } } } } }
2025-10-31 13:48:04,657 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 78c338bc-5c86-490e-aa9a-bcecb8451fd6, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,658 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 78c338bc-5c86-490e-aa9a-bcecb8451fd6, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ODE3ZjI3ZjItZGE1YzI3N2QtMWY2NjhmZWYtMzE4NTE3OGU=" tx_id: "01k8x8d01bbg757bg5d1rqvepw" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,660 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 78c338bc-5c86-490e-aa9a-bcecb8451fd6, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 13:48:04,677 - INFO - ya.test - pytest_runtest_teardown: Test teardown
