2025-12-08 23:27:53,882 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:53,891 - INFO - ya.test - pytest_runtest_setup: test_prevents_observed_transaction_vanishes_otv
2025-12-08 23:27:53,891 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:53,892 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-08 23:27:53,914 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_prevents_observed_transaction_vanishes_otv)
2025-12-08 23:27:53,919 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, bbe911ed-5009-4e25-afb8-73283244a7ae, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:53,930 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, bbe911ed-5009-4e25-afb8-73283244a7ae, ghrun-sfoiezpm3a.auto.internal:1948): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:53,968 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, bbe911ed-5009-4e25-afb8-73283244a7ae, 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=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" } } } }
2025-12-08 23:27:53,990 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 000eea33-f6aa-4233-8787-11122a0e1773, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:53,991 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 000eea33-f6aa-4233-8787-11122a0e1773, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" 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-12-08 23:27:54,061 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 000eea33-f6aa-4233-8787-11122a0e1773, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:27:54,062 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b45cebec-12ce-4e35-b6e2-16e5b2524f5a, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,063 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b45cebec-12ce-4e35-b6e2-16e5b2524f5a, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" 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-12-08 23:27:54,090 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b45cebec-12ce-4e35-b6e2-16e5b2524f5a, 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:54,130 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f8e5b314-b0b5-4744-9594-f6b76bfb4ea0, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,130 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f8e5b314-b0b5-4744-9594-f6b76bfb4ea0, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" 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-12-08 23:27:54,222 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f8e5b314-b0b5-4744-9594-f6b76bfb4ea0, 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: "01kc04p0b29xs1mk2gha61xqnm" } } } } }
2025-12-08 23:27:54,262 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0ac3c4e8-ec83-4354-9d6a-8fc45ef57457, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,269 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0ac3c4e8-ec83-4354-9d6a-8fc45ef57457, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" tx_control { tx_id: "01kc04p0b29xs1mk2gha61xqnm" } 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-12-08 23:27:54,292 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0ac3c4e8-ec83-4354-9d6a-8fc45ef57457, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p0b29xs1mk2gha61xqnm" } } } } }
2025-12-08 23:27:54,293 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 87caafab-d719-4c50-ba2f-f692cf4c358e, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,301 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 87caafab-d719-4c50-ba2f-f692cf4c358e, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" tx_control { tx_id: "01kc04p0b29xs1mk2gha61xqnm" } 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-12-08 23:27:54,349 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 87caafab-d719-4c50-ba2f-f692cf4c358e, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p0b29xs1mk2gha61xqnm" } } } } }
2025-12-08 23:27:54,351 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 839966fd-4e6b-473f-851b-f1681485855d, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,353 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 839966fd-4e6b-473f-851b-f1681485855d, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" 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-12-08 23:27:54,408 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 839966fd-4e6b-473f-851b-f1681485855d, 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: "01kc04p0gxcp0xvwy5v1f4aj8y" } } } } }
2025-12-08 23:27:54,409 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 36a57c87-d238-4dc3-85f3-35c123031ffd, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,409 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 36a57c87-d238-4dc3-85f3-35c123031ffd, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" tx_control { tx_id: "01kc04p0gxcp0xvwy5v1f4aj8y" } 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-12-08 23:27:54,446 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 36a57c87-d238-4dc3-85f3-35c123031ffd, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p0gxcp0xvwy5v1f4aj8y" } } } } }
2025-12-08 23:27:54,542 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, e8d7cacb-12df-4c91-9826-173593b45522, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,582 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, e8d7cacb-12df-4c91-9826-173593b45522, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" tx_id: "01kc04p0b29xs1mk2gha61xqnm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:54,618 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, e8d7cacb-12df-4c91-9826-173593b45522, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-08 23:27:54,619 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 2c12f5d6-4a46-42db-abdc-233b3924d748, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,626 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 2c12f5d6-4a46-42db-abdc-233b3924d748, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" 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-12-08 23:27:54,684 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 2c12f5d6-4a46-42db-abdc-233b3924d748, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04p0sr2w0v3rj2aqbp4m53" } } } } }
2025-12-08 23:27:54,686 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 140c1bd3-104f-4358-8555-7962d886c8e4, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,686 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 140c1bd3-104f-4358-8555-7962d886c8e4, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" tx_control { tx_id: "01kc04p0gxcp0xvwy5v1f4aj8y" } 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-12-08 23:27:54,707 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 140c1bd3-104f-4358-8555-7962d886c8e4, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p0gxcp0xvwy5v1f4aj8y" } } } } }
2025-12-08 23:27:54,708 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, d7ab4e0b-a713-4cc6-9ae8-f83ea430de97, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,710 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, d7ab4e0b-a713-4cc6-9ae8-f83ea430de97, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" tx_id: "01kc04p0gxcp0xvwy5v1f4aj8y" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:54,738 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, d7ab4e0b-a713-4cc6-9ae8-f83ea430de97, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_prevents_observed_transaction_vanishes_otv`" issue_code: 2001 severity: 1 } } }
2025-12-08 23:27:54,739 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0958f87b-e6d3-4eaa-b6ae-e457a047c250, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,739 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0958f87b-e6d3-4eaa-b6ae-e457a047c250, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" tx_control { tx_id: "01kc04p0sr2w0v3rj2aqbp4m53" } 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-12-08 23:27:54,768 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0958f87b-e6d3-4eaa-b6ae-e457a047c250, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04p0sr2w0v3rj2aqbp4m53" } } } } }
2025-12-08 23:27:54,771 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 62fce3b9-ce10-4f61-ab1d-0c0b51e14b36, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,772 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 62fce3b9-ce10-4f61-ab1d-0c0b51e14b36, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" tx_control { tx_id: "01kc04p0sr2w0v3rj2aqbp4m53" } 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-12-08 23:27:54,796 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 62fce3b9-ce10-4f61-ab1d-0c0b51e14b36, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04p0sr2w0v3rj2aqbp4m53" } } } } }
2025-12-08 23:27:54,861 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5b2b0773-9970-4a93-9f43-0456a25918e6, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,878 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5b2b0773-9970-4a93-9f43-0456a25918e6, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" tx_control { tx_id: "01kc04p0sr2w0v3rj2aqbp4m53" } 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-12-08 23:27:54,930 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5b2b0773-9970-4a93-9f43-0456a25918e6, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04p0sr2w0v3rj2aqbp4m53" } } } } }
2025-12-08 23:27:54,934 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 0ffbebe7-865a-4cd2-83f7-bfd8c0c97a1e, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:54,934 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 0ffbebe7-865a-4cd2-83f7-bfd8c0c97a1e, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=MjY0Mjg5YjQtYzQ1NThlNGItODU3YjM3MDgtNjM1M2U4ZmM=" tx_id: "01kc04p0sr2w0v3rj2aqbp4m53" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:54,938 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 0ffbebe7-865a-4cd2-83f7-bfd8c0c97a1e, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-08 23:27:54,943 - INFO - ya.test - pytest_runtest_teardown: Test teardown
