2025-12-12 10:05:16,418 - INFO - ya.test.2060683  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:16,425 - INFO - ya.test.2060683  - pytest_runtest_setup: test_prevents_circular_information_flow_g1c
2025-12-12 10:05:16,426 - INFO - ya.test.2060683  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:16,426 - INFO - ya.test.2060683  - pytest_runtest_setup: Test setup
2025-12-12 10:05:16,455 - INFO - ya.test.2060683  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_circular_information_flow_g1c)
2025-12-12 10:05:16,455 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CreateSession, 841f9fa8-44ff-401d-8a55-91ae46eeb6e0, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,456 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CreateSession, 841f9fa8-44ff-401d-8a55-91ae46eeb6e0, ghrun-llwi4fnhxu.auto.internal:27863): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:16,459 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CreateSession, 841f9fa8-44ff-401d-8a55-91ae46eeb6e0, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MzFmYjdlZS01NzMyNjM4MS03MDkzMDVkZS02MWQzYjJlNg==" } } } }
2025-12-12 10:05:16,468 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteSchemeQuery, 017be4c0-1f3b-4cd1-a23c-65cb6b2a1fb3, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,468 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteSchemeQuery, 017be4c0-1f3b-4cd1-a23c-65cb6b2a1fb3, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzFmYjdlZS01NzMyNjM4MS03MDkzMDVkZS02MWQzYjJlNg==" 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-12-12 10:05:16,517 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteSchemeQuery, 017be4c0-1f3b-4cd1-a23c-65cb6b2a1fb3, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:16,522 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 43264c81-6d50-4f82-8b7f-fdd7400a373d, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,523 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 43264c81-6d50-4f82-8b7f-fdd7400a373d, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzFmYjdlZS01NzMyNjM4MS03MDkzMDVkZS02MWQzYjJlNg==" 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-12-12 10:05:16,551 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 43264c81-6d50-4f82-8b7f-fdd7400a373d, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-12 10:05:16,554 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, ee60d12e-2f76-4710-ac28-458d0c0ba8f4, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,555 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, ee60d12e-2f76-4710-ac28-458d0c0ba8f4, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzFmYjdlZS01NzMyNjM4MS03MDkzMDVkZS02MWQzYjJlNg==" 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-12-12 10:05:16,590 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, ee60d12e-2f76-4710-ac28-458d0c0ba8f4, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90b7dc4tx3a5w7hcc0parq" } } } } }
2025-12-12 10:05:16,601 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 9757ec08-a7f8-4435-8899-4d3765df9780, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,601 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 9757ec08-a7f8-4435-8899-4d3765df9780, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzFmYjdlZS01NzMyNjM4MS03MDkzMDVkZS02MWQzYjJlNg==" 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-12-12 10:05:16,644 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 9757ec08-a7f8-4435-8899-4d3765df9780, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90b7f295r48tsceqfdczg5" } } } } }
2025-12-12 10:05:16,649 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, fc1a87cb-d830-42f0-a6e3-8b9072426951, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,659 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, fc1a87cb-d830-42f0-a6e3-8b9072426951, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzFmYjdlZS01NzMyNjM4MS03MDkzMDVkZS02MWQzYjJlNg==" tx_control { tx_id: "01kc90b7dc4tx3a5w7hcc0parq" } 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-12-12 10:05:16,692 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, fc1a87cb-d830-42f0-a6e3-8b9072426951, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90b7dc4tx3a5w7hcc0parq" } } } } }
2025-12-12 10:05:16,695 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 2d3bf0d3-a22f-4f0b-b612-85078c72a265, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,695 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 2d3bf0d3-a22f-4f0b-b612-85078c72a265, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzFmYjdlZS01NzMyNjM4MS03MDkzMDVkZS02MWQzYjJlNg==" tx_control { tx_id: "01kc90b7f295r48tsceqfdczg5" } 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-12-12 10:05:16,728 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 2d3bf0d3-a22f-4f0b-b612-85078c72a265, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90b7f295r48tsceqfdczg5" } } } } }
2025-12-12 10:05:16,744 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, 0aa0383d-cd50-4623-831c-5fef8d3a55ce, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,745 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, 0aa0383d-cd50-4623-831c-5fef8d3a55ce, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzFmYjdlZS01NzMyNjM4MS03MDkzMDVkZS02MWQzYjJlNg==" tx_id: "01kc90b7dc4tx3a5w7hcc0parq" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:16,763 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, 0aa0383d-cd50-4623-831c-5fef8d3a55ce, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-12 10:05:16,768 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, 8000dc7d-20da-4416-9f91-89334f95292f, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,769 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, 8000dc7d-20da-4416-9f91-89334f95292f, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=MzFmYjdlZS01NzMyNjM4MS03MDkzMDVkZS02MWQzYjJlNg==" tx_id: "01kc90b7f295r48tsceqfdczg5" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:16,781 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, 8000dc7d-20da-4416-9f91-89334f95292f, ghrun-llwi4fnhxu.auto.internal:27863): 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-12-12 10:05:16,821 - INFO - ya.test.2060683  - pytest_runtest_teardown: Test teardown
