2025-10-31 15:41:10,902 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:10,903 - INFO - ya.test - pytest_runtest_setup: test_prevents_observed_transaction_vanishes_otv
2025-10-31 15:41:10,903 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:10,912 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:41:10,922 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_observed_transaction_vanishes_otv)
2025-10-31 15:41:10,935 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7c727213-a3ac-46e0-b71d-8b433f1ed4b7, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,935 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7c727213-a3ac-46e0-b71d-8b433f1ed4b7, ghrun-xldpwfo7ey.auto.internal:6264): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:10,938 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7c727213-a3ac-46e0-b71d-8b433f1ed4b7, 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=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" } } } }
2025-10-31 15:41:10,951 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 4e082d9c-b4e4-4ede-8b87-380f2a4ac975, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:10,952 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 4e082d9c-b4e4-4ede-8b87-380f2a4ac975, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" yql_text: "create table test_prevents_observed_transaction_vanishes_otv (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:10,987 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 4e082d9c-b4e4-4ede-8b87-380f2a4ac975, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:11,006 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 69b65a0b-d6c1-4c8a-8fd2-547f83a01354, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,006 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 69b65a0b-d6c1-4c8a-8fd2-547f83a01354, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_prevents_observed_transaction_vanishes_otv (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,028 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 69b65a0b-d6c1-4c8a-8fd2-547f83a01354, 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:11,029 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 74050d87-12f5-4f07-bb38-83c1e648150c, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,031 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 74050d87-12f5-4f07-bb38-83c1e648150c, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select * from test_prevents_observed_transaction_vanishes_otv where id=1 or id=2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,082 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 74050d87-12f5-4f07-bb38-83c1e648150c, 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 } } rows { items { int32_value: 2 } items { int32_value: 20 } } format: FORMAT_VALUE } tx_meta { id: "01k8xew3h6a5r0mgpt2mshsepp" } } } } }
2025-10-31 15:41:11,097 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c227922a-9733-4dc1-903d-45891b65f285, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,098 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c227922a-9733-4dc1-903d-45891b65f285, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { tx_id: "01k8xew3h6a5r0mgpt2mshsepp" } query { yql_text: " upsert into test_prevents_observed_transaction_vanishes_otv (id, value) values (1, 11);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,118 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c227922a-9733-4dc1-903d-45891b65f285, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew3h6a5r0mgpt2mshsepp" } } } } }
2025-10-31 15:41:11,119 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 726b3213-4d73-48ba-b747-7a3b88af4bcc, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,120 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 726b3213-4d73-48ba-b747-7a3b88af4bcc, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { tx_id: "01k8xew3h6a5r0mgpt2mshsepp" } query { yql_text: " upsert into test_prevents_observed_transaction_vanishes_otv (id, value) values (2, 19);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,142 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 726b3213-4d73-48ba-b747-7a3b88af4bcc, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew3h6a5r0mgpt2mshsepp" } } } } }
2025-10-31 15:41:11,186 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5de87500-536a-4be2-b773-95e2d0c012bd, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,187 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5de87500-536a-4be2-b773-95e2d0c012bd, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select * from test_prevents_observed_transaction_vanishes_otv where id=1" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,220 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5de87500-536a-4be2-b773-95e2d0c012bd, 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: "01k8xew3ng7cz6401ma1e2amx7" } } } } }
2025-10-31 15:41:11,228 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7db84f76-9827-423c-b909-2904178dbd9a, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,242 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7db84f76-9827-423c-b909-2904178dbd9a, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { tx_id: "01k8xew3ng7cz6401ma1e2amx7" } query { yql_text: " upsert into test_prevents_observed_transaction_vanishes_otv (id, value) values (1, 12);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,312 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7db84f76-9827-423c-b909-2904178dbd9a, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew3ng7cz6401ma1e2amx7" } } } } }
2025-10-31 15:41:11,313 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, e871d9dd-8229-428d-a294-2943dbc385e9, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,316 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, e871d9dd-8229-428d-a294-2943dbc385e9, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_id: "01k8xew3h6a5r0mgpt2mshsepp" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,332 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, e871d9dd-8229-428d-a294-2943dbc385e9, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 15:41:11,352 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b0b0e51e-cf73-415f-a24b-c17f7470ea5f, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,358 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b0b0e51e-cf73-415f-a24b-c17f7470ea5f, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select value from test_prevents_observed_transaction_vanishes_otv where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,398 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b0b0e51e-cf73-415f-a24b-c17f7470ea5f, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 11 } } format: FORMAT_VALUE } tx_meta { id: "01k8xew3v16x7j7kbz2698fq9h" } } } } }
2025-10-31 15:41:11,399 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 25e62529-e5d7-4a79-91c3-0aa8a742590c, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,400 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 25e62529-e5d7-4a79-91c3-0aa8a742590c, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { tx_id: "01k8xew3ng7cz6401ma1e2amx7" } query { yql_text: " upsert into test_prevents_observed_transaction_vanishes_otv (id, value) values (2, 18);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,428 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 25e62529-e5d7-4a79-91c3-0aa8a742590c, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew3ng7cz6401ma1e2amx7" } } } } }
2025-10-31 15:41:11,464 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 568f905c-5f40-4ee3-b0b6-c3370483f153, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,465 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 568f905c-5f40-4ee3-b0b6-c3370483f153, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_id: "01k8xew3ng7cz6401ma1e2amx7" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,477 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 568f905c-5f40-4ee3-b0b6-c3370483f153, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_prevents_observed_transaction_vanishes_otv`." 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:11,487 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c6dd4697-8b3d-4ead-9a2c-003e3de35171, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,490 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c6dd4697-8b3d-4ead-9a2c-003e3de35171, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { tx_id: "01k8xew3v16x7j7kbz2698fq9h" } query { yql_text: " select value from test_prevents_observed_transaction_vanishes_otv where id = 2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,514 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c6dd4697-8b3d-4ead-9a2c-003e3de35171, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 19 } } format: FORMAT_VALUE } tx_meta { id: "01k8xew3v16x7j7kbz2698fq9h" } } } } }
2025-10-31 15:41:11,518 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 9caa1c5f-015f-4c88-b7ed-7097781e1462, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,520 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 9caa1c5f-015f-4c88-b7ed-7097781e1462, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { tx_id: "01k8xew3v16x7j7kbz2698fq9h" } query { yql_text: " select value from test_prevents_observed_transaction_vanishes_otv where id = 2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,542 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 9caa1c5f-015f-4c88-b7ed-7097781e1462, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 19 } } format: FORMAT_VALUE } tx_meta { id: "01k8xew3v16x7j7kbz2698fq9h" } } } } }
2025-10-31 15:41:11,547 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 2b115de1-63d1-42a1-a076-d86635213b4b, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,549 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 2b115de1-63d1-42a1-a076-d86635213b4b, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_control { tx_id: "01k8xew3v16x7j7kbz2698fq9h" } query { yql_text: " select value from test_prevents_observed_transaction_vanishes_otv where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,587 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 2b115de1-63d1-42a1-a076-d86635213b4b, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 11 } } format: FORMAT_VALUE } tx_meta { id: "01k8xew3v16x7j7kbz2698fq9h" } } } } }
2025-10-31 15:41:11,588 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 2025a910-0c51-4c3b-ab4f-868d48e9f076, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:11,595 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 2025a910-0c51-4c3b-ab4f-868d48e9f076, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=OGU5NWNmMjktZTg1OGRlMTktYzRiNGI4M2QtODE1YWZmZDc=" tx_id: "01k8xew3v16x7j7kbz2698fq9h" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:11,600 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 2025a910-0c51-4c3b-ab4f-868d48e9f076, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 15:41:11,618 - INFO - ya.test - pytest_runtest_teardown: Test teardown
