2026-03-25 17:14:53,559 - INFO - ya.test.2499227  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:53,559 - INFO - ya.test.2499227  - pytest_runtest_setup: test_prevents_observed_transaction_vanishes_otv
2026-03-25 17:14:53,560 - INFO - ya.test.2499227  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:53,560 - INFO - ya.test.2499227  - pytest_runtest_setup: Test setup
2026-03-25 17:14:53,561 - INFO - ya.test.2499227  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_observed_transaction_vanishes_otv)
2026-03-25 17:14:53,561 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CreateSession, cd9e3237-f3bc-4aee-8a2e-3c21add22774, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,562 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CreateSession, cd9e3237-f3bc-4aee-8a2e-3c21add22774, ghrun-ve2ie6ihly.auto.internal:65362): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:53,563 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CreateSession, cd9e3237-f3bc-4aee-8a2e-3c21add22774, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" } } } }
2026-03-25 17:14:53,564 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteSchemeQuery, 5a45c236-7986-496d-aae3-f05ae27af935, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,564 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteSchemeQuery, 5a45c236-7986-496d-aae3-f05ae27af935, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" 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 } } }
2026-03-25 17:14:53,580 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteSchemeQuery, 5a45c236-7986-496d-aae3-f05ae27af935, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS } }
2026-03-25 17:14:53,581 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, bebaa91b-4a03-4c0a-be56-6925eced628d, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,582 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, bebaa91b-4a03-4c0a-be56-6925eced628d, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" 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 } } }
2026-03-25 17:14:53,592 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, bebaa91b-4a03-4c0a-be56-6925eced628d, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2026-03-25 17:14:53,592 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, de558de0-b28b-4ca0-a9f9-293f0ececd98, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,593 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, de558de0-b28b-4ca0-a9f9-293f0ececd98, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" 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 } } }
2026-03-25 17:14:53,619 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, de558de0-b28b-4ca0-a9f9-293f0ececd98, ghrun-ve2ie6ihly.auto.internal:65362): 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: "01kmjzvx9g80wmhenjt5t5zkz7" } } } } }
2026-03-25 17:14:53,619 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 1213e3de-8f48-4abd-8635-7c3086665a8b, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,620 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 1213e3de-8f48-4abd-8635-7c3086665a8b, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" tx_control { tx_id: "01kmjzvx9g80wmhenjt5t5zkz7" } 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 } } }
2026-03-25 17:14:53,627 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 1213e3de-8f48-4abd-8635-7c3086665a8b, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvx9g80wmhenjt5t5zkz7" } } } } }
2026-03-25 17:14:53,628 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, e52bd671-bb01-4f4c-8430-2f38a33db8ea, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,628 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, e52bd671-bb01-4f4c-8430-2f38a33db8ea, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" tx_control { tx_id: "01kmjzvx9g80wmhenjt5t5zkz7" } 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 } } }
2026-03-25 17:14:53,635 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, e52bd671-bb01-4f4c-8430-2f38a33db8ea, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvx9g80wmhenjt5t5zkz7" } } } } }
2026-03-25 17:14:53,636 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 7c891a46-718e-4f87-a89c-bb6f9e2c5843, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,636 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 7c891a46-718e-4f87-a89c-bb6f9e2c5843, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" 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 } } }
2026-03-25 17:14:53,650 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 7c891a46-718e-4f87-a89c-bb6f9e2c5843, ghrun-ve2ie6ihly.auto.internal:65362): 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: "01kmjzvxaf9bajrz68bvrg5zys" } } } } }
2026-03-25 17:14:53,651 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 4d361b44-629a-46bd-b313-2a0d33effb76, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,651 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 4d361b44-629a-46bd-b313-2a0d33effb76, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" tx_control { tx_id: "01kmjzvxaf9bajrz68bvrg5zys" } 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 } } }
2026-03-25 17:14:53,661 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 4d361b44-629a-46bd-b313-2a0d33effb76, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvxaf9bajrz68bvrg5zys" } } } } }
2026-03-25 17:14:53,661 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, 7171dbd9-7293-4702-bff7-89f880d09a5a, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,661 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, 7171dbd9-7293-4702-bff7-89f880d09a5a, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" tx_id: "01kmjzvx9g80wmhenjt5t5zkz7" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:53,666 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, 7171dbd9-7293-4702-bff7-89f880d09a5a, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-03-25 17:14:53,666 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 0ea9577a-a145-47a6-a8c8-24c04646ad3a, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,667 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 0ea9577a-a145-47a6-a8c8-24c04646ad3a, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" 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 } } }
2026-03-25 17:14:53,682 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 0ea9577a-a145-47a6-a8c8-24c04646ad3a, ghrun-ve2ie6ihly.auto.internal:65362): 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: "01kmjzvxbf9ykvnqrwvm0wgc8t" } } } } }
2026-03-25 17:14:53,683 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 15a5562c-89f2-42c8-a0fd-62590377972e, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,683 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 15a5562c-89f2-42c8-a0fd-62590377972e, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" tx_control { tx_id: "01kmjzvxaf9bajrz68bvrg5zys" } 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 } } }
2026-03-25 17:14:53,693 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 15a5562c-89f2-42c8-a0fd-62590377972e, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvxaf9bajrz68bvrg5zys" } } } } }
2026-03-25 17:14:53,694 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, 52ae0644-2ca8-47bc-ad62-aa54f38d773e, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,694 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, 52ae0644-2ca8-47bc-ad62-aa54f38d773e, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" tx_id: "01kmjzvxaf9bajrz68bvrg5zys" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:53,697 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, 52ae0644-2ca8-47bc-ad62-aa54f38d773e, ghrun-ve2ie6ihly.auto.internal:65362): 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 } } }
2026-03-25 17:14:53,698 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 916b61f3-6ad0-4184-b642-9dc11773ebf8, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,698 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 916b61f3-6ad0-4184-b642-9dc11773ebf8, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" tx_control { tx_id: "01kmjzvxbf9ykvnqrwvm0wgc8t" } 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 } } }
2026-03-25 17:14:53,713 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 916b61f3-6ad0-4184-b642-9dc11773ebf8, ghrun-ve2ie6ihly.auto.internal:65362): 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: "01kmjzvxbf9ykvnqrwvm0wgc8t" } } } } }
2026-03-25 17:14:53,714 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 03d9abe4-6f9f-47d2-a2a2-4b8af7d708c5, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,714 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 03d9abe4-6f9f-47d2-a2a2-4b8af7d708c5, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" tx_control { tx_id: "01kmjzvxbf9ykvnqrwvm0wgc8t" } 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 } } }
2026-03-25 17:14:53,727 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 03d9abe4-6f9f-47d2-a2a2-4b8af7d708c5, ghrun-ve2ie6ihly.auto.internal:65362): 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: "01kmjzvxbf9ykvnqrwvm0wgc8t" } } } } }
2026-03-25 17:14:53,728 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 61a8af5e-b634-43d8-95ea-c07835a71e7d, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,729 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 61a8af5e-b634-43d8-95ea-c07835a71e7d, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" tx_control { tx_id: "01kmjzvxbf9ykvnqrwvm0wgc8t" } 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 } } }
2026-03-25 17:14:53,745 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 61a8af5e-b634-43d8-95ea-c07835a71e7d, ghrun-ve2ie6ihly.auto.internal:65362): 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: "01kmjzvxbf9ykvnqrwvm0wgc8t" } } } } }
2026-03-25 17:14:53,746 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, 0b16521a-07c7-4f3b-8872-13ecd76bd279, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:53,747 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, 0b16521a-07c7-4f3b-8872-13ecd76bd279, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZjQwOTllOWUtMWNjZGMzYTItZTJiYjg2MjMtNzRiYzJjM2U%3D" tx_id: "01kmjzvxbf9ykvnqrwvm0wgc8t" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:53,748 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, 0b16521a-07c7-4f3b-8872-13ecd76bd279, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-03-25 17:14:53,750 - INFO - ya.test.2499227  - pytest_runtest_teardown: Test teardown
