2026-08-19 13:41:24,410 - INFO - ya.test.1603699  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:41:24,410 - INFO - ya.test.1603699  - pytest_runtest_setup: test_prevents_observed_transaction_vanishes_otv
2026-08-19 13:41:24,410 - INFO - ya.test.1603699  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:41:24,410 - INFO - ya.test.1603699  - pytest_runtest_setup: Test setup
2026-08-19 13:41:24,411 - INFO - ya.test.1603699  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_observed_transaction_vanishes_otv)
2026-08-19 13:41:24,411 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CreateSession, c2833d34-e228-4e36-91ec-7349161d2a01, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,411 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CreateSession, c2833d34-e228-4e36-91ec-7349161d2a01, ghrun-4q22hfe2g4.auto.internal:22374): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,413 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CreateSession, c2833d34-e228-4e36-91ec-7349161d2a01, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" } } } }
2026-08-19 13:41:24,413 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteSchemeQuery, e5c70675-c842-43fd-999c-747397417d71, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,414 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteSchemeQuery, e5c70675-c842-43fd-999c-747397417d71, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" 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-08-19 13:41:24,430 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteSchemeQuery, e5c70675-c842-43fd-999c-747397417d71, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:41:24,431 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 64c7216a-fe76-4914-8c8a-3837a2e1d00d, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,431 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 64c7216a-fe76-4914-8c8a-3837a2e1d00d, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" 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-08-19 13:41:24,442 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 64c7216a-fe76-4914-8c8a-3837a2e1d00d, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2026-08-19 13:41:24,443 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, d9d72958-e8bb-4764-b510-d9591eee4345, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,443 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, d9d72958-e8bb-4764-b510-d9591eee4345, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" 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-08-19 13:41:24,471 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, d9d72958-e8bb-4764-b510-d9591eee4345, ghrun-4q22hfe2g4.auto.internal:22374): 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: "01m0d42nbm4wtwc6anm7hy1qb4" } } } } }
2026-08-19 13:41:24,472 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, a2e5296a-d35e-4bef-b084-4ceafc893be6, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,472 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, a2e5296a-d35e-4bef-b084-4ceafc893be6, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" tx_control { tx_id: "01m0d42nbm4wtwc6anm7hy1qb4" } 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-08-19 13:41:24,480 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, a2e5296a-d35e-4bef-b084-4ceafc893be6, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42nbm4wtwc6anm7hy1qb4" } } } } }
2026-08-19 13:41:24,480 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, cf1c07a6-1a13-4113-a069-cce1291e2850, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,481 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, cf1c07a6-1a13-4113-a069-cce1291e2850, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" tx_control { tx_id: "01m0d42nbm4wtwc6anm7hy1qb4" } 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-08-19 13:41:24,489 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, cf1c07a6-1a13-4113-a069-cce1291e2850, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42nbm4wtwc6anm7hy1qb4" } } } } }
2026-08-19 13:41:24,490 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, ea3d0bf5-5b74-49e6-9ff7-4ff4afd5cca8, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,490 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, ea3d0bf5-5b74-49e6-9ff7-4ff4afd5cca8, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" 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-08-19 13:41:24,503 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, ea3d0bf5-5b74-49e6-9ff7-4ff4afd5cca8, ghrun-4q22hfe2g4.auto.internal:22374): 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: "01m0d42ncm64m50qzf2asq923h" } } } } }
2026-08-19 13:41:24,504 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, b9466d9f-ada4-4c5e-882a-1838070add27, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,505 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, b9466d9f-ada4-4c5e-882a-1838070add27, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" tx_control { tx_id: "01m0d42ncm64m50qzf2asq923h" } 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-08-19 13:41:24,513 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, b9466d9f-ada4-4c5e-882a-1838070add27, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42ncm64m50qzf2asq923h" } } } } }
2026-08-19 13:41:24,514 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, 4e7f3b03-af51-4501-8fa4-72fdad0648b7, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,514 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, 4e7f3b03-af51-4501-8fa4-72fdad0648b7, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" tx_id: "01m0d42nbm4wtwc6anm7hy1qb4" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,518 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, 4e7f3b03-af51-4501-8fa4-72fdad0648b7, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-08-19 13:41:24,518 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 32c81128-be9a-4738-9a54-caaeb566028c, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,519 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 32c81128-be9a-4738-9a54-caaeb566028c, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" 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-08-19 13:41:24,533 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 32c81128-be9a-4738-9a54-caaeb566028c, ghrun-4q22hfe2g4.auto.internal:22374): 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: "01m0d42ndj1awrk8159w700512" } } } } }
2026-08-19 13:41:24,534 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 01ca54c7-dff5-4232-8ffe-12a20f2f3839, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,534 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 01ca54c7-dff5-4232-8ffe-12a20f2f3839, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" tx_control { tx_id: "01m0d42ncm64m50qzf2asq923h" } 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-08-19 13:41:24,543 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 01ca54c7-dff5-4232-8ffe-12a20f2f3839, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42ncm64m50qzf2asq923h" } } } } }
2026-08-19 13:41:24,544 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, 61fc7c94-613e-449b-a928-a7d1682d3cbc, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,544 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, 61fc7c94-613e-449b-a928-a7d1682d3cbc, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" tx_id: "01m0d42ncm64m50qzf2asq923h" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,547 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, 61fc7c94-613e-449b-a928-a7d1682d3cbc, ghrun-4q22hfe2g4.auto.internal:22374): 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-08-19 13:41:24,547 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 3bd46a93-0614-43a2-a7fc-02676501bfca, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,548 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 3bd46a93-0614-43a2-a7fc-02676501bfca, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" tx_control { tx_id: "01m0d42ndj1awrk8159w700512" } 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-08-19 13:41:24,562 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 3bd46a93-0614-43a2-a7fc-02676501bfca, ghrun-4q22hfe2g4.auto.internal:22374): 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: "01m0d42ndj1awrk8159w700512" } } } } }
2026-08-19 13:41:24,563 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, fe15617f-6601-4879-98c1-4dd66eb15e47, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,564 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, fe15617f-6601-4879-98c1-4dd66eb15e47, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" tx_control { tx_id: "01m0d42ndj1awrk8159w700512" } 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-08-19 13:41:24,579 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, fe15617f-6601-4879-98c1-4dd66eb15e47, ghrun-4q22hfe2g4.auto.internal:22374): 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: "01m0d42ndj1awrk8159w700512" } } } } }
2026-08-19 13:41:24,580 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 615df873-91f2-4e48-9b9d-ae789d1ce287, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,581 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 615df873-91f2-4e48-9b9d-ae789d1ce287, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" tx_control { tx_id: "01m0d42ndj1awrk8159w700512" } 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-08-19 13:41:24,596 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 615df873-91f2-4e48-9b9d-ae789d1ce287, ghrun-4q22hfe2g4.auto.internal:22374): 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: "01m0d42ndj1awrk8159w700512" } } } } }
2026-08-19 13:41:24,596 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, 3031a437-c2a4-464f-b49f-3ceefc428eb9, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,597 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, 3031a437-c2a4-464f-b49f-3ceefc428eb9, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OWI4NTNkMDAtZDk4ZGI4ODEtYTViNzA4Y2MtMmY1ZjllNGM=" tx_id: "01m0d42ndj1awrk8159w700512" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,598 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, 3031a437-c2a4-464f-b49f-3ceefc428eb9, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-08-19 13:41:24,599 - INFO - ya.test.1603699  - pytest_runtest_teardown: Test teardown
