2026-08-19 13:41:24,187 - INFO - ya.test.1603699  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:41:24,188 - INFO - ya.test.1603699  - pytest_runtest_setup: test_prevents_circular_information_flow_g1c
2026-08-19 13:41:24,188 - INFO - ya.test.1603699  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:41:24,188 - INFO - ya.test.1603699  - pytest_runtest_setup: Test setup
2026-08-19 13:41:24,188 - INFO - ya.test.1603699  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_circular_information_flow_g1c)
2026-08-19 13:41:24,189 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CreateSession, 1d99aa82-28b5-498f-816d-1db4829b5958, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,189 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CreateSession, 1d99aa82-28b5-498f-816d-1db4829b5958, ghrun-4q22hfe2g4.auto.internal:22374): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,190 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CreateSession, 1d99aa82-28b5-498f-816d-1db4829b5958, 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=OGEwN2FjNzgtYTMwOTBjODUtZThlNDZmNjktNjlkZDgxNWI=" } } } }
2026-08-19 13:41:24,191 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteSchemeQuery, 516e832d-a9fe-436d-883f-ff2a86828c71, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,191 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteSchemeQuery, 516e832d-a9fe-436d-883f-ff2a86828c71, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OGEwN2FjNzgtYTMwOTBjODUtZThlNDZmNjktNjlkZDgxNWI=" yql_text: "create table test_prevents_circular_information_flow_g1c (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,206 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteSchemeQuery, 516e832d-a9fe-436d-883f-ff2a86828c71, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:41:24,207 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, ecd899c4-1f56-43d2-b6e9-0e507283ca2f, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,207 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, ecd899c4-1f56-43d2-b6e9-0e507283ca2f, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OGEwN2FjNzgtYTMwOTBjODUtZThlNDZmNjktNjlkZDgxNWI=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_prevents_circular_information_flow_g1c (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,218 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, ecd899c4-1f56-43d2-b6e9-0e507283ca2f, 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,218 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 74bd83ce-7d75-4259-8524-8fd4fc54a37a, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,219 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 74bd83ce-7d75-4259-8524-8fd4fc54a37a, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OGEwN2FjNzgtYTMwOTBjODUtZThlNDZmNjktNjlkZDgxNWI=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: "update test_prevents_circular_information_flow_g1c set value = 11 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,235 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 74bd83ce-7d75-4259-8524-8fd4fc54a37a, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42n498g9a60zm0a53j3aj" } } } } }
2026-08-19 13:41:24,235 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 86b3da28-d95d-46b7-a5c7-669775bbd160, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,236 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 86b3da28-d95d-46b7-a5c7-669775bbd160, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OGEwN2FjNzgtYTMwOTBjODUtZThlNDZmNjktNjlkZDgxNWI=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: "update test_prevents_circular_information_flow_g1c set value = 22 where id = 2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,253 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 86b3da28-d95d-46b7-a5c7-669775bbd160, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42n4v5sy8ec8az9p5h46p" } } } } }
2026-08-19 13:41:24,254 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 166e108b-9a43-4578-96f5-f4fa463fb26a, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,254 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 166e108b-9a43-4578-96f5-f4fa463fb26a, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OGEwN2FjNzgtYTMwOTBjODUtZThlNDZmNjktNjlkZDgxNWI=" tx_control { tx_id: "01m0d42n498g9a60zm0a53j3aj" } query { yql_text: "select * from test_prevents_circular_information_flow_g1c where id = 2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,269 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 166e108b-9a43-4578-96f5-f4fa463fb26a, 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: 2 } items { int32_value: 20 } } format: FORMAT_VALUE } tx_meta { id: "01m0d42n498g9a60zm0a53j3aj" } } } } }
2026-08-19 13:41:24,269 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, bb6d9a99-8c8a-4fca-9747-dec2b6703413, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,270 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, bb6d9a99-8c8a-4fca-9747-dec2b6703413, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OGEwN2FjNzgtYTMwOTBjODUtZThlNDZmNjktNjlkZDgxNWI=" tx_control { tx_id: "01m0d42n4v5sy8ec8az9p5h46p" } query { yql_text: "select * from test_prevents_circular_information_flow_g1c where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,286 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, bb6d9a99-8c8a-4fca-9747-dec2b6703413, 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: "01m0d42n4v5sy8ec8az9p5h46p" } } } } }
2026-08-19 13:41:24,286 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, 36e552dd-08af-4fe2-bc88-a7b1907916a4, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,286 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, 36e552dd-08af-4fe2-bc88-a7b1907916a4, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OGEwN2FjNzgtYTMwOTBjODUtZThlNDZmNjktNjlkZDgxNWI=" tx_id: "01m0d42n498g9a60zm0a53j3aj" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,289 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, 36e552dd-08af-4fe2-bc88-a7b1907916a4, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-08-19 13:41:24,289 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, 708001cb-fdff-49dc-875b-f0a7bd7a8738, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:24,290 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, 708001cb-fdff-49dc-875b-f0a7bd7a8738, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=OGEwN2FjNzgtYTMwOTBjODUtZThlNDZmNjktNjlkZDgxNWI=" tx_id: "01m0d42n4v5sy8ec8az9p5h46p" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:24,292 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, 708001cb-fdff-49dc-875b-f0a7bd7a8738, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_prevents_circular_information_flow_g1c`." 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,294 - INFO - ya.test.1603699  - pytest_runtest_teardown: Test teardown
