2025-11-26 18:41:39,808 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:39,809 - INFO - ya.test.2332018  - pytest_runtest_setup: test_isolation_mailing_list_example
2025-11-26 18:41:39,809 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:39,809 - INFO - ya.test.2332018  - pytest_runtest_setup: Test setup
2025-11-26 18:41:39,810 - INFO - ya.test.2332018  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_isolation_mailing_list_example)
2025-11-26 18:41:39,811 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CreateSession, c931d678-1c20-4973-b7e0-cdd7401ba5a2, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,812 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CreateSession, c931d678-1c20-4973-b7e0-cdd7401ba5a2, ghrun-ihttfokzrq.auto.internal:8570): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,815 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CreateSession, c931d678-1c20-4973-b7e0-cdd7401ba5a2, 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=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" } } } }
2025-11-26 18:41:39,819 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteSchemeQuery, 0b8440f4-f129-4e7f-bc37-c026821b41e4, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,820 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteSchemeQuery, 0b8440f4-f129-4e7f-bc37-c026821b41e4, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" yql_text: "create table test_isolation_mailing_list_example (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,849 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteSchemeQuery, 0b8440f4-f129-4e7f-bc37-c026821b41e4, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:41:39,850 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 253ded44-9def-4c4c-b7cd-47dbb7b3e6a6, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,850 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 253ded44-9def-4c4c-b7cd-47dbb7b3e6a6, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_isolation_mailing_list_example (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,881 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 253ded44-9def-4c4c-b7cd-47dbb7b3e6a6, 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:39,881 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(PrepareDataQuery, cc8ab7b0-ed9e-419a-aef1-fdaf510b1fc5, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,882 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(PrepareDataQuery, cc8ab7b0-ed9e-419a-aef1-fdaf510b1fc5, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 3);" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,900 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(PrepareDataQuery, cc8ab7b0-ed9e-419a-aef1-fdaf510b1fc5, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=bb759290-48752d48-6df6c10e-b384e42" } } } }
2025-11-26 18:41:39,901 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, e792638c-0b3d-4d0d-8abd-37624de43d99, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,902 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, e792638c-0b3d-4d0d-8abd-37624de43d99, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 3);" } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,907 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, e792638c-0b3d-4d0d-8abd-37624de43d99, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } query_meta { id: "ydb://preparedqueryid/4?id=bb759290-48752d48-6df6c10e-b384e42" } } } } }
2025-11-26 18:41:39,908 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(PrepareDataQuery, 45daa0b5-0a8e-4e5d-82e7-c8344cea035d, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,908 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(PrepareDataQuery, 45daa0b5-0a8e-4e5d-82e7-c8344cea035d, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" yql_text: " select id, value FROM test_isolation_mailing_list_example WHERE id = 1" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,928 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(PrepareDataQuery, 45daa0b5-0a8e-4e5d-82e7-c8344cea035d, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=c8fec950-8c29e8d9-b945d3d2-637cd87c" } } } }
2025-11-26 18:41:39,929 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, ada3f4ec-4cc4-4aa5-b8da-35f58888b227, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,930 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, ada3f4ec-4cc4-4aa5-b8da-35f58888b227, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value FROM test_isolation_mailing_list_example WHERE id = 1" } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,935 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, ada3f4ec-4cc4-4aa5-b8da-35f58888b227, 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: 3 } } format: FORMAT_VALUE } tx_meta { id: "01kb0qh8jvaz6a6pwz9p93rz04" } query_meta { id: "ydb://preparedqueryid/4?id=c8fec950-8c29e8d9-b945d3d2-637cd87c" } } } } }
2025-11-26 18:41:39,936 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, ba81774b-6433-4131-aa47-7e5f562742e4, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,937 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, ba81774b-6433-4131-aa47-7e5f562742e4, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value FROM test_isolation_mailing_list_example WHERE id = 1" } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,941 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, ba81774b-6433-4131-aa47-7e5f562742e4, 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: 3 } } format: FORMAT_VALUE } tx_meta { id: "01kb0qh8k2aw93jegj1qefz6kx" } query_meta { id: "ydb://preparedqueryid/4?id=c8fec950-8c29e8d9-b945d3d2-637cd87c" } } } } }
2025-11-26 18:41:39,943 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(PrepareDataQuery, b1d21367-2b5a-4eed-9e63-490e6102973f, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,944 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(PrepareDataQuery, b1d21367-2b5a-4eed-9e63-490e6102973f, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 4);" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,960 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(PrepareDataQuery, b1d21367-2b5a-4eed-9e63-490e6102973f, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=2574e131-a67a688f-733fdee5-a4b5fb2e" } } } }
2025-11-26 18:41:39,961 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 48dc6c19-92e4-4650-b516-71b8b5a8df53, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,962 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 48dc6c19-92e4-4650-b516-71b8b5a8df53, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" tx_control { tx_id: "01kb0qh8jvaz6a6pwz9p93rz04" } query { yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 4);" } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,964 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 48dc6c19-92e4-4650-b516-71b8b5a8df53, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh8jvaz6a6pwz9p93rz04" } query_meta { id: "ydb://preparedqueryid/4?id=2574e131-a67a688f-733fdee5-a4b5fb2e" } } } } }
2025-11-26 18:41:39,965 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, d356c006-c9da-46c2-bb42-bcec6075c011, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,966 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, d356c006-c9da-46c2-bb42-bcec6075c011, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" tx_id: "01kb0qh8jvaz6a6pwz9p93rz04" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,971 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, d356c006-c9da-46c2-bb42-bcec6075c011, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-11-26 18:41:39,972 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(PrepareDataQuery, 0f546b8a-3c42-47c8-9900-2fe97e1cfb73, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,972 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(PrepareDataQuery, 0f546b8a-3c42-47c8-9900-2fe97e1cfb73, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 5);" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,985 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(PrepareDataQuery, 0f546b8a-3c42-47c8-9900-2fe97e1cfb73, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=4b4987dc-1a713cef-39d97b92-b338da98" } } } }
2025-11-26 18:41:39,986 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, d21b7088-5add-476d-83f0-be01c26cf38d, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,987 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, d21b7088-5add-476d-83f0-be01c26cf38d, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" tx_control { tx_id: "01kb0qh8k2aw93jegj1qefz6kx" } query { yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 5);" } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,989 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, d21b7088-5add-476d-83f0-be01c26cf38d, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qh8k2aw93jegj1qefz6kx" } query_meta { id: "ydb://preparedqueryid/4?id=4b4987dc-1a713cef-39d97b92-b338da98" } } } } }
2025-11-26 18:41:39,990 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, e1d307a9-cba1-4bd5-b7b8-d56c522722c8, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,990 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, e1d307a9-cba1-4bd5-b7b8-d56c522722c8, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" tx_id: "01kb0qh8k2aw93jegj1qefz6kx" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:39,995 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, e1d307a9-cba1-4bd5-b7b8-d56c522722c8, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_isolation_mailing_list_example`." issue_code: 2001 severity: 1 issues { message: "Operation is aborting because locks are not valid" issue_code: 2001 severity: 1 } } } }
2025-11-26 18:41:39,996 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, b52eff36-4ef1-48c7-9f58-25f85bbb586f, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:39,999 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, b52eff36-4ef1-48c7-9f58-25f85bbb586f, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=NjkzM2VjMTYtNGQyODBkY2UtYTMxZjdmZjUtOTUyY2Y2Yjk=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: " select id, value from test_isolation_mailing_list_example where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:40,020 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, b52eff36-4ef1-48c7-9f58-25f85bbb586f, 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: 4 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-11-26 18:41:40,022 - INFO - ya.test.2332018  - pytest_runtest_teardown: Test teardown
