2025-10-31 13:48:03,578 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:03,579 - INFO - ya.test - pytest_runtest_setup: test_prevents_circular_information_flow_g1c
2025-10-31 13:48:03,579 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:03,579 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 13:48:03,580 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_circular_information_flow_g1c)
2025-10-31 13:48:03,581 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a26a15eb-9112-47a4-878d-b7f2cd67ca0e, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,581 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a26a15eb-9112-47a4-878d-b7f2cd67ca0e, ghrun-e45ihybpyu.auto.internal:20918): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:03,584 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a26a15eb-9112-47a4-878d-b7f2cd67ca0e, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZmU0ZGQzZmYtNDU0YzIxYjMtMzg0ZTE1MzAtMjI4YjRiMTA=" } } } }
2025-10-31 13:48:03,584 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 8974d2d9-97ff-49f7-a47e-15d47b223d3f, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,588 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 8974d2d9-97ff-49f7-a47e-15d47b223d3f, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZmU0ZGQzZmYtNDU0YzIxYjMtMzg0ZTE1MzAtMjI4YjRiMTA=" 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-10-31 13:48:03,611 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 8974d2d9-97ff-49f7-a47e-15d47b223d3f, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:48:03,612 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e01c06d3-6890-4b98-884a-9005362e91d1, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,614 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e01c06d3-6890-4b98-884a-9005362e91d1, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZmU0ZGQzZmYtNDU0YzIxYjMtMzg0ZTE1MzAtMjI4YjRiMTA=" 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-10-31 13:48:03,638 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e01c06d3-6890-4b98-884a-9005362e91d1, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 13:48:03,639 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 83c9d1ae-4089-450c-9ab4-716908c514fc, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,648 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 83c9d1ae-4089-450c-9ab4-716908c514fc, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZmU0ZGQzZmYtNDU0YzIxYjMtMzg0ZTE1MzAtMjI4YjRiMTA=" 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-10-31 13:48:03,675 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 83c9d1ae-4089-450c-9ab4-716908c514fc, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8cz6t38j0dva49prvkhfh" } } } } }
2025-10-31 13:48:03,676 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 70d32824-d9aa-4d7c-ab69-d4710ea4a875, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,677 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 70d32824-d9aa-4d7c-ab69-d4710ea4a875, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZmU0ZGQzZmYtNDU0YzIxYjMtMzg0ZTE1MzAtMjI4YjRiMTA=" 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-10-31 13:48:03,706 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 70d32824-d9aa-4d7c-ab69-d4710ea4a875, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8cz7ra5y0v9k9y0reyc8k" } } } } }
2025-10-31 13:48:03,708 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, d2b42f12-7ba6-4b55-b9c0-94515399aad0, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,709 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, d2b42f12-7ba6-4b55-b9c0-94515399aad0, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZmU0ZGQzZmYtNDU0YzIxYjMtMzg0ZTE1MzAtMjI4YjRiMTA=" tx_control { tx_id: "01k8x8cz6t38j0dva49prvkhfh" } 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-10-31 13:48:03,738 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, d2b42f12-7ba6-4b55-b9c0-94515399aad0, ghrun-e45ihybpyu.auto.internal:20918): 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: "01k8x8cz6t38j0dva49prvkhfh" } } } } }
2025-10-31 13:48:03,739 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 31e6db9e-31e2-40bb-bfea-e66753dbca0c, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,740 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 31e6db9e-31e2-40bb-bfea-e66753dbca0c, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZmU0ZGQzZmYtNDU0YzIxYjMtMzg0ZTE1MzAtMjI4YjRiMTA=" tx_control { tx_id: "01k8x8cz7ra5y0v9k9y0reyc8k" } 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-10-31 13:48:03,788 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 31e6db9e-31e2-40bb-bfea-e66753dbca0c, ghrun-e45ihybpyu.auto.internal:20918): 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: "01k8x8cz7ra5y0v9k9y0reyc8k" } } } } }
2025-10-31 13:48:03,789 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, be7d56eb-b27e-4a98-af05-66e4a0466dd2, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,790 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, be7d56eb-b27e-4a98-af05-66e4a0466dd2, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZmU0ZGQzZmYtNDU0YzIxYjMtMzg0ZTE1MzAtMjI4YjRiMTA=" tx_id: "01k8x8cz6t38j0dva49prvkhfh" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:03,801 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, be7d56eb-b27e-4a98-af05-66e4a0466dd2, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 13:48:03,803 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 29821702-01fa-4073-b918-d5f4fff7afb3, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,803 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 29821702-01fa-4073-b918-d5f4fff7afb3, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=ZmU0ZGQzZmYtNDU0YzIxYjMtMzg0ZTE1MzAtMjI4YjRiMTA=" tx_id: "01k8x8cz7ra5y0v9k9y0reyc8k" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:03,813 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 29821702-01fa-4073-b918-d5f4fff7afb3, ghrun-e45ihybpyu.auto.internal:20918): 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-10-31 13:48:03,846 - INFO - ya.test - pytest_runtest_teardown: Test teardown
