2025-12-12 10:05:17,502 - INFO - ya.test.2060683  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:17,510 - INFO - ya.test.2060683  - pytest_runtest_setup: test_prevents_observed_transaction_vanishes_otv
2025-12-12 10:05:17,518 - INFO - ya.test.2060683  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:17,518 - INFO - ya.test.2060683  - pytest_runtest_setup: Test setup
2025-12-12 10:05:17,524 - INFO - ya.test.2060683  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_observed_transaction_vanishes_otv)
2025-12-12 10:05:17,524 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CreateSession, 6e70d2e7-9d47-4aea-94ff-a6885285d309, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,547 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CreateSession, 6e70d2e7-9d47-4aea-94ff-a6885285d309, ghrun-llwi4fnhxu.auto.internal:27863): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,555 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CreateSession, 6e70d2e7-9d47-4aea-94ff-a6885285d309, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" } } } }
2025-12-12 10:05:17,558 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteSchemeQuery, 8d3f46b8-f5c1-462c-a5cd-140b8298c7ec, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,558 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteSchemeQuery, 8d3f46b8-f5c1-462c-a5cd-140b8298c7ec, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" 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-12-12 10:05:17,608 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteSchemeQuery, 8d3f46b8-f5c1-462c-a5cd-140b8298c7ec, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:17,638 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 50626865-5ee9-4524-a82e-3adce34fb335, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,653 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 50626865-5ee9-4524-a82e-3adce34fb335, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" 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-12-12 10:05:17,673 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 50626865-5ee9-4524-a82e-3adce34fb335, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-12 10:05:17,685 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 3db92750-47f2-4d90-b6ef-59c9a7028268, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,686 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 3db92750-47f2-4d90-b6ef-59c9a7028268, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" 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-12-12 10:05:17,757 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 3db92750-47f2-4d90-b6ef-59c9a7028268, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90b8hr05rtqaaqzj5kwpm9" } } } } }
2025-12-12 10:05:17,758 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, daef0ae7-c073-489e-a952-99e2b8d61bc0, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,764 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, daef0ae7-c073-489e-a952-99e2b8d61bc0, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" tx_control { tx_id: "01kc90b8hr05rtqaaqzj5kwpm9" } 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-12-12 10:05:17,777 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, daef0ae7-c073-489e-a952-99e2b8d61bc0, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90b8hr05rtqaaqzj5kwpm9" } } } } }
2025-12-12 10:05:17,778 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 6313e0fe-cd4d-4bff-9408-5b6e3d80c51c, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,782 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 6313e0fe-cd4d-4bff-9408-5b6e3d80c51c, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" tx_control { tx_id: "01kc90b8hr05rtqaaqzj5kwpm9" } 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-12-12 10:05:17,799 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 6313e0fe-cd4d-4bff-9408-5b6e3d80c51c, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90b8hr05rtqaaqzj5kwpm9" } } } } }
2025-12-12 10:05:17,822 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, cfcc9b36-51af-447f-a667-715fb6a51134, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,826 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, cfcc9b36-51af-447f-a667-715fb6a51134, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" 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-12-12 10:05:17,854 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, cfcc9b36-51af-447f-a667-715fb6a51134, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90b8mta6fc0gnv3qbnkdtp" } } } } }
2025-12-12 10:05:17,863 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, a08cbaee-a7be-4d0f-9f22-95a224d81077, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,867 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, a08cbaee-a7be-4d0f-9f22-95a224d81077, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" tx_control { tx_id: "01kc90b8mta6fc0gnv3qbnkdtp" } 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-12-12 10:05:17,882 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, a08cbaee-a7be-4d0f-9f22-95a224d81077, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90b8mta6fc0gnv3qbnkdtp" } } } } }
2025-12-12 10:05:17,883 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, f1db41fb-ee7c-4357-bdc9-1fd6583f06db, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,884 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, f1db41fb-ee7c-4357-bdc9-1fd6583f06db, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" tx_id: "01kc90b8hr05rtqaaqzj5kwpm9" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,891 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, f1db41fb-ee7c-4357-bdc9-1fd6583f06db, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-12 10:05:17,891 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 88cf53a7-eeff-4d94-ad8b-b0fb15ec1bcd, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,892 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 88cf53a7-eeff-4d94-ad8b-b0fb15ec1bcd, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" 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-12-12 10:05:17,919 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 88cf53a7-eeff-4d94-ad8b-b0fb15ec1bcd, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90b8ptckgdjhd7s660xxb1" } } } } }
2025-12-12 10:05:17,920 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, ce26723b-34c5-4610-aa3a-487cd3613dc0, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,921 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, ce26723b-34c5-4610-aa3a-487cd3613dc0, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" tx_control { tx_id: "01kc90b8mta6fc0gnv3qbnkdtp" } 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-12-12 10:05:17,937 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, ce26723b-34c5-4610-aa3a-487cd3613dc0, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90b8mta6fc0gnv3qbnkdtp" } } } } }
2025-12-12 10:05:17,944 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, f044dd73-40d2-406c-989f-36fba58f30d4, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,944 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, f044dd73-40d2-406c-989f-36fba58f30d4, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" tx_id: "01kc90b8mta6fc0gnv3qbnkdtp" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,954 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, f044dd73-40d2-406c-989f-36fba58f30d4, ghrun-llwi4fnhxu.auto.internal:27863): 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-12-12 10:05:17,966 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, bedcd8ff-8481-4323-ba6c-78b88aa52c00, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,970 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, bedcd8ff-8481-4323-ba6c-78b88aa52c00, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" tx_control { tx_id: "01kc90b8ptckgdjhd7s660xxb1" } 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-12-12 10:05:17,997 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, bedcd8ff-8481-4323-ba6c-78b88aa52c00, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90b8ptckgdjhd7s660xxb1" } } } } }
2025-12-12 10:05:18,003 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 0520e7c9-6cb0-4b4f-8533-aebde92a6463, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:18,023 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 0520e7c9-6cb0-4b4f-8533-aebde92a6463, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" tx_control { tx_id: "01kc90b8ptckgdjhd7s660xxb1" } 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-12-12 10:05:18,049 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 0520e7c9-6cb0-4b4f-8533-aebde92a6463, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90b8ptckgdjhd7s660xxb1" } } } } }
2025-12-12 10:05:18,050 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, c9340ac3-4cd0-4dd0-bdd3-5f8477a3d6ca, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:18,051 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, c9340ac3-4cd0-4dd0-bdd3-5f8477a3d6ca, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" tx_control { tx_id: "01kc90b8ptckgdjhd7s660xxb1" } 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-12-12 10:05:18,073 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, c9340ac3-4cd0-4dd0-bdd3-5f8477a3d6ca, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90b8ptckgdjhd7s660xxb1" } } } } }
2025-12-12 10:05:18,074 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, a1c8bf12-834a-44c2-9ebc-5e8e8d8fa661, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:18,078 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, a1c8bf12-834a-44c2-9ebc-5e8e8d8fa661, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzlmOTVhYTMtMjdjMGJlYzktNjdkOTljNjEtYjk1Yjc5NmY=" tx_id: "01kc90b8ptckgdjhd7s660xxb1" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:18,093 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, a1c8bf12-834a-44c2-9ebc-5e8e8d8fa661, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-12 10:05:18,106 - INFO - ya.test.2060683  - pytest_runtest_teardown: Test teardown
