2025-12-08 23:27:52,435 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:52,436 - INFO - ya.test - pytest_runtest_setup: test_prevents_circular_information_flow_g1c
2025-12-08 23:27:52,436 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:52,436 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-08 23:27:52,437 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_circular_information_flow_g1c)
2025-12-08 23:27:52,470 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2469e767-e74d-4821-b833-f4321d9c38c2, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:52,471 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2469e767-e74d-4821-b833-f4321d9c38c2, ghrun-sfoiezpm3a.auto.internal:1948): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:52,473 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2469e767-e74d-4821-b833-f4321d9c38c2, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=Zjc1ZjNkMGEtOGY3NGUwNjQtNTA1YmJlMDctMjJlYzRmOWE=" } } } }
2025-12-08 23:27:52,474 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, d09bc68d-e897-4d24-a608-e514ed4bc493, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:52,474 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, d09bc68d-e897-4d24-a608-e514ed4bc493, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Zjc1ZjNkMGEtOGY3NGUwNjQtNTA1YmJlMDctMjJlYzRmOWE=" 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-08 23:27:52,515 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, d09bc68d-e897-4d24-a608-e514ed4bc493, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:27:52,566 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0a43b3d8-4cc8-4760-897e-73d6be16a612, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:52,571 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0a43b3d8-4cc8-4760-897e-73d6be16a612, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Zjc1ZjNkMGEtOGY3NGUwNjQtNTA1YmJlMDctMjJlYzRmOWE=" 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-08 23:27:52,613 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0a43b3d8-4cc8-4760-897e-73d6be16a612, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:27:52,620 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, fb4d2722-b37c-45b4-94f4-573e9c3eac05, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:52,627 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, fb4d2722-b37c-45b4-94f4-573e9c3eac05, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Zjc1ZjNkMGEtOGY3NGUwNjQtNTA1YmJlMDctMjJlYzRmOWE=" 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-08 23:27:52,677 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, fb4d2722-b37c-45b4-94f4-573e9c3eac05, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04nytz09r2abxgxf5n2k52" } } } } }
2025-12-08 23:27:52,678 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 56d22356-3807-4f91-a8f7-4a9d90a2eed8, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:52,688 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 56d22356-3807-4f91-a8f7-4a9d90a2eed8, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Zjc1ZjNkMGEtOGY3NGUwNjQtNTA1YmJlMDctMjJlYzRmOWE=" 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-08 23:27:52,736 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 56d22356-3807-4f91-a8f7-4a9d90a2eed8, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04nywm9gz9xkpfd7w3y1cc" } } } } }
2025-12-08 23:27:52,737 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, fe68654a-70b6-4d34-9163-fbac4851b582, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:52,739 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, fe68654a-70b6-4d34-9163-fbac4851b582, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Zjc1ZjNkMGEtOGY3NGUwNjQtNTA1YmJlMDctMjJlYzRmOWE=" tx_control { tx_id: "01kc04nytz09r2abxgxf5n2k52" } 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-08 23:27:52,763 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, fe68654a-70b6-4d34-9163-fbac4851b582, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04nytz09r2abxgxf5n2k52" } } } } }
2025-12-08 23:27:52,764 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0ccae0e2-077b-45ee-8ae7-c9fd704c6674, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:52,765 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0ccae0e2-077b-45ee-8ae7-c9fd704c6674, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Zjc1ZjNkMGEtOGY3NGUwNjQtNTA1YmJlMDctMjJlYzRmOWE=" tx_control { tx_id: "01kc04nywm9gz9xkpfd7w3y1cc" } 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-08 23:27:52,817 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0ccae0e2-077b-45ee-8ae7-c9fd704c6674, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04nywm9gz9xkpfd7w3y1cc" } } } } }
2025-12-08 23:27:52,853 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 7564b869-dfd2-4648-a8cc-3d468dc06a12, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:52,866 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 7564b869-dfd2-4648-a8cc-3d468dc06a12, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Zjc1ZjNkMGEtOGY3NGUwNjQtNTA1YmJlMDctMjJlYzRmOWE=" tx_id: "01kc04nytz09r2abxgxf5n2k52" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:52,903 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 7564b869-dfd2-4648-a8cc-3d468dc06a12, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-08 23:27:52,906 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 3b2f9f75-27f3-4139-bc71-6e777600e1c7, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:52,906 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 3b2f9f75-27f3-4139-bc71-6e777600e1c7, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Zjc1ZjNkMGEtOGY3NGUwNjQtNTA1YmJlMDctMjJlYzRmOWE=" tx_id: "01kc04nywm9gz9xkpfd7w3y1cc" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:52,948 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 3b2f9f75-27f3-4139-bc71-6e777600e1c7, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_prevents_circular_information_flow_g1c`" issue_code: 2001 severity: 1 } } }
2025-12-08 23:27:53,011 - INFO - ya.test - pytest_runtest_teardown: Test teardown
