2025-10-31 15:41:10,124 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:10,131 - INFO - ya.test - pytest_runtest_setup: test_prevents_circular_information_flow_g1c
2025-10-31 15:41:10,132 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:10,140 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:41:10,148 - 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 15:41:10,149 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 18ac1120-ec05-4519-bb05-62c876403454, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,151 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 18ac1120-ec05-4519-bb05-62c876403454, ghrun-xldpwfo7ey.auto.internal:6264): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:10,158 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 18ac1120-ec05-4519-bb05-62c876403454, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MjM0YzkxNzQtMTVmY2QwYy0zODhhMzRkNS1jMjY0MGNmNQ==" } } } }
2025-10-31 15:41:10,163 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, d239cff1-7c10-4ff0-8e39-3ac089f896ba, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,164 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, d239cff1-7c10-4ff0-8e39-3ac089f896ba, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MjM0YzkxNzQtMTVmY2QwYy0zODhhMzRkNS1jMjY0MGNmNQ==" 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 15:41:10,205 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, d239cff1-7c10-4ff0-8e39-3ac089f896ba, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:10,207 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 44f28c3f-8123-4d0b-868c-e5337fc58137, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,207 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 44f28c3f-8123-4d0b-868c-e5337fc58137, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MjM0YzkxNzQtMTVmY2QwYy0zODhhMzRkNS1jMjY0MGNmNQ==" 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 15:41:10,227 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 44f28c3f-8123-4d0b-868c-e5337fc58137, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 15:41:10,228 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 03a919f4-e107-4ae7-9203-adda56f17e6e, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,229 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 03a919f4-e107-4ae7-9203-adda56f17e6e, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MjM0YzkxNzQtMTVmY2QwYy0zODhhMzRkNS1jMjY0MGNmNQ==" 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 15:41:10,259 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 03a919f4-e107-4ae7-9203-adda56f17e6e, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew2qh38vgxzbgjg11ba0a" } } } } }
2025-10-31 15:41:10,263 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c7ccea02-68ca-44b5-a2ac-d9bba7954bce, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,264 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c7ccea02-68ca-44b5-a2ac-d9bba7954bce, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MjM0YzkxNzQtMTVmY2QwYy0zODhhMzRkNS1jMjY0MGNmNQ==" 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 15:41:10,297 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c7ccea02-68ca-44b5-a2ac-d9bba7954bce, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew2rmfsr8k5b0envn6a58" } } } } }
2025-10-31 15:41:10,303 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a602deda-9a1e-4b27-b2f7-37d87727dc1b, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,308 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a602deda-9a1e-4b27-b2f7-37d87727dc1b, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MjM0YzkxNzQtMTVmY2QwYy0zODhhMzRkNS1jMjY0MGNmNQ==" tx_control { tx_id: "01k8xew2qh38vgxzbgjg11ba0a" } 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 15:41:10,333 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a602deda-9a1e-4b27-b2f7-37d87727dc1b, ghrun-xldpwfo7ey.auto.internal:6264): 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: "01k8xew2qh38vgxzbgjg11ba0a" } } } } }
2025-10-31 15:41:10,334 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5e65a6ab-abe3-4697-be94-7a8144a18e26, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,335 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5e65a6ab-abe3-4697-be94-7a8144a18e26, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MjM0YzkxNzQtMTVmY2QwYy0zODhhMzRkNS1jMjY0MGNmNQ==" tx_control { tx_id: "01k8xew2rmfsr8k5b0envn6a58" } 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 15:41:10,361 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5e65a6ab-abe3-4697-be94-7a8144a18e26, ghrun-xldpwfo7ey.auto.internal:6264): 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: "01k8xew2rmfsr8k5b0envn6a58" } } } } }
2025-10-31 15:41:10,368 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 0b848bd7-ff33-4b8c-9321-4db7ac2e2bde, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,370 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 0b848bd7-ff33-4b8c-9321-4db7ac2e2bde, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MjM0YzkxNzQtMTVmY2QwYy0zODhhMzRkNS1jMjY0MGNmNQ==" tx_id: "01k8xew2qh38vgxzbgjg11ba0a" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:10,383 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 0b848bd7-ff33-4b8c-9321-4db7ac2e2bde, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 15:41:10,394 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, dadedbef-9d2d-46d1-8e0d-e87611f153eb, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,395 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, dadedbef-9d2d-46d1-8e0d-e87611f153eb, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=MjM0YzkxNzQtMTVmY2QwYy0zODhhMzRkNS1jMjY0MGNmNQ==" tx_id: "01k8xew2rmfsr8k5b0envn6a58" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:10,411 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, dadedbef-9d2d-46d1-8e0d-e87611f153eb, ghrun-xldpwfo7ey.auto.internal:6264): 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 15:41:10,435 - INFO - ya.test - pytest_runtest_teardown: Test teardown
