2025-11-26 18:41:42,203 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:42,204 - INFO - ya.test.2332018  - pytest_runtest_setup: test_anti_dependency_cycles_g2_two_edges
2025-11-26 18:41:42,204 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:42,204 - INFO - ya.test.2332018  - pytest_runtest_setup: Test setup
2025-11-26 18:41:42,205 - INFO - ya.test.2332018  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_anti_dependency_cycles_g2_two_edges)
2025-11-26 18:41:42,206 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CreateSession, 40456fc0-a2f7-462b-af01-5f3ea0cf1a46, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,207 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CreateSession, 40456fc0-a2f7-462b-af01-5f3ea0cf1a46, ghrun-ihttfokzrq.auto.internal:8570): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,209 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CreateSession, 40456fc0-a2f7-462b-af01-5f3ea0cf1a46, 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=OGEzMTVjNWMtM2I5ZjVmNTktYWVlNmQ5OTUtODlmODMxNA==" } } } }
2025-11-26 18:41:42,210 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteSchemeQuery, 11b2029b-a510-45f8-b390-cd98a9281c33, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,210 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteSchemeQuery, 11b2029b-a510-45f8-b390-cd98a9281c33, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OGEzMTVjNWMtM2I5ZjVmNTktYWVlNmQ5OTUtODlmODMxNA==" yql_text: "create table test_anti_dependency_cycles_g2_two_edges (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,236 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteSchemeQuery, 11b2029b-a510-45f8-b390-cd98a9281c33, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:41:42,237 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 57586aa6-86e7-4984-afd1-97f1d4f048af, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,237 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 57586aa6-86e7-4984-afd1-97f1d4f048af, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OGEzMTVjNWMtM2I5ZjVmNTktYWVlNmQ5OTUtODlmODMxNA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_anti_dependency_cycles_g2_two_edges (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,254 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 57586aa6-86e7-4984-afd1-97f1d4f048af, 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:42,255 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 2cef22e6-c5ab-4cdb-9cbd-d37f399384ca, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,256 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 2cef22e6-c5ab-4cdb-9cbd-d37f399384ca, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OGEzMTVjNWMtM2I5ZjVmNTktYWVlNmQ5OTUtODlmODMxNA==" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value from test_anti_dependency_cycles_g2_two_edges;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,280 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 2cef22e6-c5ab-4cdb-9cbd-d37f399384ca, 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 } } rows { items { int32_value: 2 } items { int32_value: 20 } } format: FORMAT_VALUE } tx_meta { id: "01kb0qhaw54weefjwzmy5v1sy1" } } } } }
2025-11-26 18:41:42,281 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, ea05877f-826d-488b-8b64-f5e8e6c5e776, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,282 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, ea05877f-826d-488b-8b64-f5e8e6c5e776, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OGEzMTVjNWMtM2I5ZjVmNTktYWVlNmQ5OTUtODlmODMxNA==" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " update test_anti_dependency_cycles_g2_two_edges set value = value + 5 where id = 2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,309 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, ea05877f-826d-488b-8b64-f5e8e6c5e776, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qhax28xpxzpgffm0sqt6h" } } } } }
2025-11-26 18:41:42,309 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, a15787db-7d9a-4673-928d-f4f20d244f14, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,310 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, a15787db-7d9a-4673-928d-f4f20d244f14, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OGEzMTVjNWMtM2I5ZjVmNTktYWVlNmQ5OTUtODlmODMxNA==" tx_id: "01kb0qhax28xpxzpgffm0sqt6h" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,320 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, a15787db-7d9a-4673-928d-f4f20d244f14, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-11-26 18:41:42,321 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 3b61551c-eae6-475e-8a06-11db9d97c7c0, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,322 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 3b61551c-eae6-475e-8a06-11db9d97c7c0, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OGEzMTVjNWMtM2I5ZjVmNTktYWVlNmQ5OTUtODlmODMxNA==" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value from test_anti_dependency_cycles_g2_two_edges;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,350 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 3b61551c-eae6-475e-8a06-11db9d97c7c0, 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 } } rows { items { int32_value: 2 } items { int32_value: 25 } } format: FORMAT_VALUE } tx_meta { id: "01kb0qhay822y2kheh9eh4j2sr" } } } } }
2025-11-26 18:41:42,351 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, 269133bf-a590-4bd3-95b9-fcee225dff73, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,352 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, 269133bf-a590-4bd3-95b9-fcee225dff73, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OGEzMTVjNWMtM2I5ZjVmNTktYWVlNmQ5OTUtODlmODMxNA==" tx_id: "01kb0qhay822y2kheh9eh4j2sr" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,358 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, 269133bf-a590-4bd3-95b9-fcee225dff73, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-11-26 18:41:42,358 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, b8a2eaca-9cbe-4484-b64f-874a648a1d88, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,359 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, b8a2eaca-9cbe-4484-b64f-874a648a1d88, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OGEzMTVjNWMtM2I5ZjVmNTktYWVlNmQ5OTUtODlmODMxNA==" tx_control { tx_id: "01kb0qhaw54weefjwzmy5v1sy1" } query { yql_text: " update test_anti_dependency_cycles_g2_two_edges set value = 0 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,387 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, b8a2eaca-9cbe-4484-b64f-874a648a1d88, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qhaw54weefjwzmy5v1sy1" } } } } }
2025-11-26 18:41:42,388 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, f49590b1-63b4-40e5-9783-4ad441549634, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,388 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, f49590b1-63b4-40e5-9783-4ad441549634, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=OGEzMTVjNWMtM2I5ZjVmNTktYWVlNmQ5OTUtODlmODMxNA==" tx_id: "01kb0qhaw54weefjwzmy5v1sy1" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,393 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, f49590b1-63b4-40e5-9783-4ad441549634, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_anti_dependency_cycles_g2_two_edges`" issue_code: 2001 severity: 1 } } }
2025-11-26 18:41:42,395 - INFO - ya.test.2332018  - pytest_runtest_teardown: Test teardown
2025-11-26 18:42:04,399 - INFO - ydb.tests.library.harness.kikimr_runner.2332018  - stop: Stopped node localhost:8570/1
2025-11-26 18:42:04,405 - DEBUG - library.python.filelock.2332018  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13137
2025-11-26 18:42:04,406 - DEBUG - library.python.filelock.2332018  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29546
2025-11-26 18:42:04,406 - DEBUG - library.python.filelock.2332018  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16659
2025-11-26 18:42:04,406 - DEBUG - library.python.filelock.2332018  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8570
2025-11-26 18:42:04,406 - DEBUG - library.python.filelock.2332018  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23137
2025-11-26 18:42:04,406 - DEBUG - ydb.topic.2332018  - close: Close topic client
2025-11-26 18:42:04,406 - DEBUG - ydb.topic.2332018  - close: Topic client was closed
2025-11-26 18:42:04,407 - DEBUG - ydb.connection.2332018  - close: Closing channel for endpoint localhost:8570
2025-11-26 18:42:04,407 - DEBUG - ydb.connection.2332018  - close: Closing channel for endpoint ghrun-ihttfokzrq.auto.internal:8570
2025-11-26 18:42:04,408 - INFO - ydb.pool.Discovery.2332018  - run: Successfully terminated discovery process
