2025-12-08 23:28:00,351 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:28:00,351 - INFO - ya.test - pytest_runtest_setup: test_anti_dependency_cycles_g2_two_edges
2025-12-08 23:28:00,352 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:28:00,352 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-08 23:28:00,362 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_anti_dependency_cycles_g2_two_edges)
2025-12-08 23:28:00,377 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, de0306fb-b7d4-47bf-be7c-17e9d507bb2e, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:28:00,466 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, de0306fb-b7d4-47bf-be7c-17e9d507bb2e, ghrun-sfoiezpm3a.auto.internal:1948): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:28:00,468 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, de0306fb-b7d4-47bf-be7c-17e9d507bb2e, 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=Mzg1Y2MwODQtMTRhNWRlOC0zYTBhYTJkZS0xZmQwZDQ3Ng==" } } } }
2025-12-08 23:28:00,479 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, bd2d7295-d0d2-482b-a633-7e444d6b75a8, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:28:00,479 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, bd2d7295-d0d2-482b-a633-7e444d6b75a8, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Mzg1Y2MwODQtMTRhNWRlOC0zYTBhYTJkZS0xZmQwZDQ3Ng==" 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-12-08 23:28:00,536 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, bd2d7295-d0d2-482b-a633-7e444d6b75a8, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:00,546 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 797eaaa5-a588-436a-a6b7-066837651781, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:28:00,547 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 797eaaa5-a588-436a-a6b7-066837651781, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Mzg1Y2MwODQtMTRhNWRlOC0zYTBhYTJkZS0xZmQwZDQ3Ng==" 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-12-08 23:28:00,627 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 797eaaa5-a588-436a-a6b7-066837651781, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:28:00,628 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 87359146-c459-4782-987a-b461aac196b0, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:28:00,634 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 87359146-c459-4782-987a-b461aac196b0, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Mzg1Y2MwODQtMTRhNWRlOC0zYTBhYTJkZS0xZmQwZDQ3Ng==" 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-12-08 23:28:00,671 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 87359146-c459-4782-987a-b461aac196b0, 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 } } rows { items { int32_value: 2 } items { int32_value: 20 } } format: FORMAT_VALUE } tx_meta { id: "01kc04p6mr59mpp8f5vz4pcgs8" } } } } }
2025-12-08 23:28:00,690 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a7602b26-7979-4265-8912-7d09bf70eb49, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:28:00,697 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a7602b26-7979-4265-8912-7d09bf70eb49, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Mzg1Y2MwODQtMTRhNWRlOC0zYTBhYTJkZS0xZmQwZDQ3Ng==" 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-12-08 23:28:00,763 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a7602b26-7979-4265-8912-7d09bf70eb49, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p6qfe3gq4g3jwqndmcj9" } } } } }
2025-12-08 23:28:00,810 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 835ceb5d-33b3-4313-a27f-6e295af030a0, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:28:00,810 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 835ceb5d-33b3-4313-a27f-6e295af030a0, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Mzg1Y2MwODQtMTRhNWRlOC0zYTBhYTJkZS0xZmQwZDQ3Ng==" tx_id: "01kc04p6qfe3gq4g3jwqndmcj9" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:28:00,849 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 835ceb5d-33b3-4313-a27f-6e295af030a0, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-08 23:28:00,850 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 308dd4af-c948-4693-a6bc-8305a33d62f0, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:28:00,862 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 308dd4af-c948-4693-a6bc-8305a33d62f0, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Mzg1Y2MwODQtMTRhNWRlOC0zYTBhYTJkZS0xZmQwZDQ3Ng==" 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-12-08 23:28:00,903 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 308dd4af-c948-4693-a6bc-8305a33d62f0, 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 } } rows { items { int32_value: 2 } items { int32_value: 25 } } format: FORMAT_VALUE } tx_meta { id: "01kc04p6w26gd41v1d8hjtkb6c" } } } } }
2025-12-08 23:28:00,906 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 5904bc5b-6fa3-4f4b-928d-7154682b13e9, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:28:00,910 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 5904bc5b-6fa3-4f4b-928d-7154682b13e9, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Mzg1Y2MwODQtMTRhNWRlOC0zYTBhYTJkZS0xZmQwZDQ3Ng==" tx_id: "01kc04p6w26gd41v1d8hjtkb6c" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:28:00,915 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 5904bc5b-6fa3-4f4b-928d-7154682b13e9, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-08 23:28:00,920 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0ab9084a-358c-43b0-b4f3-7915659f2dce, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:28:00,926 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0ab9084a-358c-43b0-b4f3-7915659f2dce, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=Mzg1Y2MwODQtMTRhNWRlOC0zYTBhYTJkZS0xZmQwZDQ3Ng==" tx_control { tx_id: "01kc04p6mr59mpp8f5vz4pcgs8" } 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-12-08 23:28:00,954 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0ab9084a-358c-43b0-b4f3-7915659f2dce, ghrun-sfoiezpm3a.auto.internal:1948): 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 } issues { message: "tx has deferred effects, but locks are broken" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p6mr59mpp8f5vz4pcgs8" } } } } }
2025-12-08 23:28:01,058 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-12-08 23:28:28,069 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:1948/1
2025-12-08 23:28:28,071 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25081
2025-12-08 23:28:28,071 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21166
2025-12-08 23:28:28,071 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64419
2025-12-08 23:28:28,072 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1948
2025-12-08 23:28:28,072 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24630
2025-12-08 23:28:28,072 - DEBUG - ydb.topic - close: Close topic client
2025-12-08 23:28:28,072 - DEBUG - ydb.topic - close: Topic client was closed
2025-12-08 23:28:28,072 - INFO - ydb.connection - close: Closing channel for endpoint localhost:1948
2025-12-08 23:28:28,073 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-sfoiezpm3a.auto.internal:1948
2025-12-08 23:28:28,073 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
