2025-11-26 18:41:39,610 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:39,610 - INFO - ya.test.2332018  - pytest_runtest_setup: test_prevents_circular_information_flow_g1c
2025-11-26 18:41:39,611 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:39,611 - INFO - ya.test.2332018  - pytest_runtest_setup: Test setup
2025-11-26 18:41:39,612 - INFO - ya.test.2332018  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_circular_information_flow_g1c)
2025-11-26 18:41:39,613 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CreateSession, b83201a3-edae-4f9c-af76-5c14dfdcbd42, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,613 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CreateSession, b83201a3-edae-4f9c-af76-5c14dfdcbd42, ghrun-ihttfokzrq.auto.internal:8570): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,616 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CreateSession, b83201a3-edae-4f9c-af76-5c14dfdcbd42, 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=ZDJkNGQ5ZmUtYmI5YTMxOTQtODgyOTA2NzgtM2QxNzFmNWY=" } } } }
2025-11-26 18:41:39,616 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteSchemeQuery, cd7071a1-6a02-4d55-9999-6bbde592d3f0, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,617 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteSchemeQuery, cd7071a1-6a02-4d55-9999-6bbde592d3f0, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDJkNGQ5ZmUtYmI5YTMxOTQtODgyOTA2NzgtM2QxNzFmNWY=" 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 } } }
2025-11-26 18:41:39,639 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteSchemeQuery, cd7071a1-6a02-4d55-9999-6bbde592d3f0, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:41:39,640 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 1d77f953-6e2a-42ad-a0b0-ff943feeee98, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,641 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 1d77f953-6e2a-42ad-a0b0-ff943feeee98, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDJkNGQ5ZmUtYmI5YTMxOTQtODgyOTA2NzgtM2QxNzFmNWY=" 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 } } }
2025-11-26 18:41:39,661 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 1d77f953-6e2a-42ad-a0b0-ff943feeee98, 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:39,662 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 74803368-7375-4f7e-a69b-ae683a51e5ca, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,662 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 74803368-7375-4f7e-a69b-ae683a51e5ca, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDJkNGQ5ZmUtYmI5YTMxOTQtODgyOTA2NzgtM2QxNzFmNWY=" 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 } } }
2025-11-26 18:41:39,689 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 74803368-7375-4f7e-a69b-ae683a51e5ca, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh8b80d0ews6awtcfnz9y" } } } } }
2025-11-26 18:41:39,690 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, d3d1e1e9-c416-4142-b604-a28bb562f6b6, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,691 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, d3d1e1e9-c416-4142-b604-a28bb562f6b6, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDJkNGQ5ZmUtYmI5YTMxOTQtODgyOTA2NzgtM2QxNzFmNWY=" 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 } } }
2025-11-26 18:41:39,718 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, d3d1e1e9-c416-4142-b604-a28bb562f6b6, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh8c4f3spmfx49w1ma97m" } } } } }
2025-11-26 18:41:39,718 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 57f14c54-0bd7-4ebb-aa75-b4d0fa126395, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,719 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 57f14c54-0bd7-4ebb-aa75-b4d0fa126395, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDJkNGQ5ZmUtYmI5YTMxOTQtODgyOTA2NzgtM2QxNzFmNWY=" tx_control { tx_id: "01kb0qh8b80d0ews6awtcfnz9y" } query { yql_text: "select * from test_prevents_circular_information_flow_g1c where id = 2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,746 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 57f14c54-0bd7-4ebb-aa75-b4d0fa126395, 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: 2 } items { int32_value: 20 } } format: FORMAT_VALUE } tx_meta { id: "01kb0qh8b80d0ews6awtcfnz9y" } } } } }
2025-11-26 18:41:39,747 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 7e76abb9-4353-4ebf-b3dc-67ef306ecc81, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,747 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 7e76abb9-4353-4ebf-b3dc-67ef306ecc81, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDJkNGQ5ZmUtYmI5YTMxOTQtODgyOTA2NzgtM2QxNzFmNWY=" tx_control { tx_id: "01kb0qh8c4f3spmfx49w1ma97m" } query { yql_text: "select * from test_prevents_circular_information_flow_g1c where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,776 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 7e76abb9-4353-4ebf-b3dc-67ef306ecc81, 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: "01kb0qh8c4f3spmfx49w1ma97m" } } } } }
2025-11-26 18:41:39,777 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, a42ca399-9635-4f62-b55e-92203a073ee8, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,777 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, a42ca399-9635-4f62-b55e-92203a073ee8, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDJkNGQ5ZmUtYmI5YTMxOTQtODgyOTA2NzgtM2QxNzFmNWY=" tx_id: "01kb0qh8b80d0ews6awtcfnz9y" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,788 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, a42ca399-9635-4f62-b55e-92203a073ee8, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-11-26 18:41:39,789 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, b5b5a4e0-f9b4-4bb2-88b8-3a64cc795f11, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,790 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, b5b5a4e0-f9b4-4bb2-88b8-3a64cc795f11, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=ZDJkNGQ5ZmUtYmI5YTMxOTQtODgyOTA2NzgtM2QxNzFmNWY=" tx_id: "01kb0qh8c4f3spmfx49w1ma97m" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,798 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, b5b5a4e0-f9b4-4bb2-88b8-3a64cc795f11, ghrun-ihttfokzrq.auto.internal:8570): 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 } } } }
2025-11-26 18:41:39,802 - INFO - ya.test.2332018  - pytest_runtest_teardown: Test teardown
