2025-11-26 18:41:40,026 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:40,027 - INFO - ya.test.2332018  - pytest_runtest_setup: test_prevents_observed_transaction_vanishes_otv
2025-11-26 18:41:40,027 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:40,027 - INFO - ya.test.2332018  - pytest_runtest_setup: Test setup
2025-11-26 18:41:40,028 - INFO - ya.test.2332018  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_observed_transaction_vanishes_otv)
2025-11-26 18:41:40,029 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CreateSession, c996b78d-6d69-45a7-a68d-86f8ee8d6364, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,029 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CreateSession, c996b78d-6d69-45a7-a68d-86f8ee8d6364, ghrun-ihttfokzrq.auto.internal:8570): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,033 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CreateSession, c996b78d-6d69-45a7-a68d-86f8ee8d6364, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" } } } }
2025-11-26 18:41:40,033 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteSchemeQuery, a8adc4db-0aee-470e-a89c-8eeb08b20429, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,034 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteSchemeQuery, a8adc4db-0aee-470e-a89c-8eeb08b20429, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" 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-11-26 18:41:40,069 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteSchemeQuery, a8adc4db-0aee-470e-a89c-8eeb08b20429, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:41:40,070 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 5d5b6afb-d4d2-492c-be2a-863d8260af3d, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,071 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 5d5b6afb-d4d2-492c-be2a-863d8260af3d, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" 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-11-26 18:41:40,092 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 5d5b6afb-d4d2-492c-be2a-863d8260af3d, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-26 18:41:40,093 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, c9d80050-d94e-465a-b7a7-0cfc251aace1, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,094 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, c9d80050-d94e-465a-b7a7-0cfc251aace1, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" 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-11-26 18:41:40,138 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, c9d80050-d94e-465a-b7a7-0cfc251aace1, ghrun-ihttfokzrq.auto.internal:8570): 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: "01kb0qh8s4fzmxvpg375zyq4fp" } } } } }
2025-11-26 18:41:40,140 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, bf5c4875-9abb-497c-8edf-6ff86eb4147b, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,143 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, bf5c4875-9abb-497c-8edf-6ff86eb4147b, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" tx_control { tx_id: "01kb0qh8s4fzmxvpg375zyq4fp" } 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-11-26 18:41:40,159 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, bf5c4875-9abb-497c-8edf-6ff86eb4147b, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh8s4fzmxvpg375zyq4fp" } } } } }
2025-11-26 18:41:40,160 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 1d472437-52e0-4e74-8103-7d30f1939e07, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,161 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 1d472437-52e0-4e74-8103-7d30f1939e07, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" tx_control { tx_id: "01kb0qh8s4fzmxvpg375zyq4fp" } 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-11-26 18:41:40,173 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 1d472437-52e0-4e74-8103-7d30f1939e07, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh8s4fzmxvpg375zyq4fp" } } } } }
2025-11-26 18:41:40,174 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 3dbf3790-83c5-485f-8fec-098b2df6acb0, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,175 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 3dbf3790-83c5-485f-8fec-098b2df6acb0, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" 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-11-26 18:41:40,196 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 3dbf3790-83c5-485f-8fec-098b2df6acb0, ghrun-ihttfokzrq.auto.internal:8570): 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: "01kb0qh8v13zj4gsqjxhbdc6tk" } } } } }
2025-11-26 18:41:40,197 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, d20063ac-d5b7-442b-8699-11eac5bffed4, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,198 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, d20063ac-d5b7-442b-8699-11eac5bffed4, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" tx_control { tx_id: "01kb0qh8v13zj4gsqjxhbdc6tk" } 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-11-26 18:41:40,213 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, d20063ac-d5b7-442b-8699-11eac5bffed4, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh8v13zj4gsqjxhbdc6tk" } } } } }
2025-11-26 18:41:40,214 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, 1cd39d84-86ab-47a8-b9c4-812e55cccca5, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,215 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, 1cd39d84-86ab-47a8-b9c4-812e55cccca5, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" tx_id: "01kb0qh8s4fzmxvpg375zyq4fp" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,220 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, 1cd39d84-86ab-47a8-b9c4-812e55cccca5, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-11-26 18:41:40,221 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 2ee6df58-3721-438e-aece-d5b77712d27d, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,221 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 2ee6df58-3721-438e-aece-d5b77712d27d, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" 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-11-26 18:41:40,241 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 2ee6df58-3721-438e-aece-d5b77712d27d, ghrun-ihttfokzrq.auto.internal:8570): 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: "01kb0qh8we0wcebjhnhsbq1nq1" } } } } }
2025-11-26 18:41:40,242 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, da6d3276-4a73-45c2-896d-5c6e01dd6c73, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,242 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, da6d3276-4a73-45c2-896d-5c6e01dd6c73, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" tx_control { tx_id: "01kb0qh8v13zj4gsqjxhbdc6tk" } 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-11-26 18:41:40,255 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, da6d3276-4a73-45c2-896d-5c6e01dd6c73, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh8v13zj4gsqjxhbdc6tk" } } } } }
2025-11-26 18:41:40,256 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, 7331b2c9-dcfa-4d41-8fe2-caf94a621bfb, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,256 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, 7331b2c9-dcfa-4d41-8fe2-caf94a621bfb, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" tx_id: "01kb0qh8v13zj4gsqjxhbdc6tk" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,260 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, 7331b2c9-dcfa-4d41-8fe2-caf94a621bfb, ghrun-ihttfokzrq.auto.internal:8570): 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-11-26 18:41:40,261 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, fdaedfc0-699f-4ab4-9e11-56cbb2d38fb0, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,261 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, fdaedfc0-699f-4ab4-9e11-56cbb2d38fb0, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" tx_control { tx_id: "01kb0qh8we0wcebjhnhsbq1nq1" } 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-11-26 18:41:40,284 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, fdaedfc0-699f-4ab4-9e11-56cbb2d38fb0, ghrun-ihttfokzrq.auto.internal:8570): 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: "01kb0qh8we0wcebjhnhsbq1nq1" } } } } }
2025-11-26 18:41:40,285 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 79cdd5a7-8140-4a93-ae65-30a958772cbe, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,285 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 79cdd5a7-8140-4a93-ae65-30a958772cbe, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" tx_control { tx_id: "01kb0qh8we0wcebjhnhsbq1nq1" } 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-11-26 18:41:40,305 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 79cdd5a7-8140-4a93-ae65-30a958772cbe, ghrun-ihttfokzrq.auto.internal:8570): 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: "01kb0qh8we0wcebjhnhsbq1nq1" } } } } }
2025-11-26 18:41:40,306 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, d923c829-0b29-4976-ac58-914c769aef42, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,307 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, d923c829-0b29-4976-ac58-914c769aef42, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" tx_control { tx_id: "01kb0qh8we0wcebjhnhsbq1nq1" } 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-11-26 18:41:40,328 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, d923c829-0b29-4976-ac58-914c769aef42, ghrun-ihttfokzrq.auto.internal:8570): 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: "01kb0qh8we0wcebjhnhsbq1nq1" } } } } }
2025-11-26 18:41:40,329 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, b1184616-75f4-4e17-9c92-204b21014f79, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:40,329 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, b1184616-75f4-4e17-9c92-204b21014f79, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OWE1NzE1NzUtNTRkMzU4MDQtNDM0N2ViNjItM2I4ZTE0MDM=" tx_id: "01kb0qh8we0wcebjhnhsbq1nq1" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,333 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, b1184616-75f4-4e17-9c92-204b21014f79, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-11-26 18:41:40,334 - INFO - ya.test.2332018  - pytest_runtest_teardown: Test teardown
