Loading...

XML

Word

Printable

JSON

Type: Task
Resolution: Gone away
Priority: Major - P3
Fix Version/s: None
Affects Version/s: None
Component/s: Tests
Labels:
None

Started failing today on latest (v4.9.0-alpha-1297-g0edda21):

 [2021/01/14 17:42:35.799] db version v4.9.0-alpha-1297-g0edda21
...
 [2021/01/14 17:52:39.986] ======================================================================
 [2021/01/14 17:52:39.986] FAIL [0.047s]: test_transactions_retryable_abort_abortTransaction_succeeds_after_WriteConcernError_InterruptedAtShutdown (test_transactions.TestTransactions)
 [2021/01/14 17:52:39.986] ----------------------------------------------------------------------
 [2021/01/14 17:52:39.986] Traceback (most recent call last):
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/test_transactions.py", line 464, in run_scenario
 [2021/01/14 17:52:39.986]     self.run_scenario(scenario_def, test)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/utils_spec_runner.py", line 559, in run_scenario
 [2021/01/14 17:52:39.986]     self.check_events(test, listener, session_ids)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/utils_spec_runner.py", line 383, in check_events
 [2021/01/14 17:52:39.986]     len(res['started']), len(test['expectations']), cmds)
 [2021/01/14 17:52:39.986] AssertionError: 2 != 3 : [{'insert': 'test', 'ordered': True, 'lsid': {'id': UUID('cc6fb0a1-a256-4926-a148-5cae52ac8af6')}, 'startTransaction': True, 'txnNumber': 1, 'autocommit': False, '$clusterTime': {'clusterTime': Timestamp(1610646733, 32), 'signature': {'hash': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', 'keyId': 0}}, '$db': 'transaction-tests', '$readPreference': {'mode': 'primary'}, 'documents': [{'_id': 1}]}, {'abortTransaction': 1, 'recoveryToken': {'recoveryShardId': 'sh01'}, 'writeConcern': {'w': 'majority'}, 'lsid': {'id': UUID('cc6fb0a1-a256-4926-a148-5cae52ac8af6')}, 'txnNumber': 1, 'autocommit': False, '$clusterTime': {'clusterTime': Timestamp(1610646733, 32), 'signature': {'hash': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', 'keyId': 0}}, '$db': 'admin', '$readPreference': {'mode': 'primary'}}]
 [2021/01/14 17:52:39.986] ======================================================================
 [2021/01/14 17:52:39.986] FAIL [0.047s]: test_transactions_retryable_abort_abortTransaction_succeeds_after_WriteConcernError_InterruptedDueToReplStateChange (test_transactions.TestTransactions)
 [2021/01/14 17:52:39.986] ----------------------------------------------------------------------
 [2021/01/14 17:52:39.986] Traceback (most recent call last):
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/test_transactions.py", line 464, in run_scenario
 [2021/01/14 17:52:39.986]     self.run_scenario(scenario_def, test)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/utils_spec_runner.py", line 559, in run_scenario
 [2021/01/14 17:52:39.986]     self.check_events(test, listener, session_ids)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/utils_spec_runner.py", line 383, in check_events
 [2021/01/14 17:52:39.986]     len(res['started']), len(test['expectations']), cmds)
 [2021/01/14 17:52:39.986] AssertionError: 2 != 3 : [{'insert': 'test', 'ordered': True, 'lsid': {'id': UUID('91487944-edfe-451d-873c-e5eae618a43d')}, 'startTransaction': True, 'txnNumber': 1, 'autocommit': False, '$clusterTime': {'clusterTime': Timestamp(1610646733, 40), 'signature': {'hash': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', 'keyId': 0}}, '$db': 'transaction-tests', '$readPreference': {'mode': 'primary'}, 'documents': [{'_id': 1}]}, {'abortTransaction': 1, 'recoveryToken': {'recoveryShardId': 'sh01'}, 'writeConcern': {'w': 'majority'}, 'lsid': {'id': UUID('91487944-edfe-451d-873c-e5eae618a43d')}, 'txnNumber': 1, 'autocommit': False, '$clusterTime': {'clusterTime': Timestamp(1610646733, 40), 'signature': {'hash': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', 'keyId': 0}}, '$db': 'admin', '$readPreference': {'mode': 'primary'}}]
 [2021/01/14 17:52:39.986] ======================================================================
 [2021/01/14 17:52:39.986] FAIL [0.043s]: test_transactions_retryable_abort_abortTransaction_succeeds_after_WriteConcernError_PrimarySteppedDown (test_transactions.TestTransactions)
 [2021/01/14 17:52:39.986] ----------------------------------------------------------------------
 [2021/01/14 17:52:39.986] Traceback (most recent call last):
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/test_transactions.py", line 464, in run_scenario
 [2021/01/14 17:52:39.986]     self.run_scenario(scenario_def, test)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/utils_spec_runner.py", line 559, in run_scenario
 [2021/01/14 17:52:39.986]     self.check_events(test, listener, session_ids)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/utils_spec_runner.py", line 383, in check_events
 [2021/01/14 17:52:39.986]     len(res['started']), len(test['expectations']), cmds)
 [2021/01/14 17:52:39.986] AssertionError: 2 != 3 : [{'insert': 'test', 'ordered': True, 'lsid': {'id': UUID('b5b05181-7778-463c-9470-57897328c3f6')}, 'startTransaction': True, 'txnNumber': 1, 'autocommit': False, '$clusterTime': {'clusterTime': Timestamp(1610646733, 48), 'signature': {'hash': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', 'keyId': 0}}, '$db': 'transaction-tests', '$readPreference': {'mode': 'primary'}, 'documents': [{'_id': 1}]}, {'abortTransaction': 1, 'recoveryToken': {'recoveryShardId': 'sh01'}, 'writeConcern': {'w': 'majority'}, 'lsid': {'id': UUID('b5b05181-7778-463c-9470-57897328c3f6')}, 'txnNumber': 1, 'autocommit': False, '$clusterTime': {'clusterTime': Timestamp(1610646733, 48), 'signature': {'hash': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', 'keyId': 0}}, '$db': 'admin', '$readPreference': {'mode': 'primary'}}]
 [2021/01/14 17:52:39.986] ======================================================================
 [2021/01/14 17:52:39.986] FAIL [0.047s]: test_transactions_retryable_abort_abortTransaction_succeeds_after_WriteConcernError_ShutdownInProgress (test_transactions.TestTransactions)
 [2021/01/14 17:52:39.986] ----------------------------------------------------------------------
 [2021/01/14 17:52:39.986] Traceback (most recent call last):
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/__init__.py", line 485, in wrap
 [2021/01/14 17:52:39.986]     return f(*args, **kwargs)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/test_transactions.py", line 464, in run_scenario
 [2021/01/14 17:52:39.986]     self.run_scenario(scenario_def, test)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/utils_spec_runner.py", line 559, in run_scenario
 [2021/01/14 17:52:39.986]     self.check_events(test, listener, session_ids)
 [2021/01/14 17:52:39.986]   File "/data/mci/579e469c3927c33613817ec697b15a2d/src/test/utils_spec_runner.py", line 383, in check_events
 [2021/01/14 17:52:39.986]     len(res['started']), len(test['expectations']), cmds)
 [2021/01/14 17:52:39.986] AssertionError: 2 != 3 : [{'insert': 'test', 'ordered': True, 'lsid': {'id': UUID('60ae6186-fa3e-4b98-89d8-c10dc81058f9')}, 'startTransaction': True, 'txnNumber': 1, 'autocommit': False, '$clusterTime': {'clusterTime': Timestamp(1610646733, 56), 'signature': {'hash': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', 'keyId': 0}}, '$db': 'transaction-tests', '$readPreference': {'mode': 'primary'}, 'documents': [{'_id': 1}]}, {'abortTransaction': 1, 'recoveryToken': {'recoveryShardId': 'sh01'}, 'writeConcern': {'w': 'majority'}, 'lsid': {'id': UUID('60ae6186-fa3e-4b98-89d8-c10dc81058f9')}, 'txnNumber': 1, 'autocommit': False, '$clusterTime': {'clusterTime': Timestamp(1610646733, 56), 'signature': {'hash': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', 'keyId': 0}}, '$db': 'admin', '$readPreference': {'mode': 'primary'}}]
 [2021/01/14 17:52:40.007] ----------------------------------------------------------------------
 [2021/01/14 17:52:40.007] Ran 2821 tests in 566.422s

https://evergreen.mongodb.com/task/mongo_python_driver_tests_python_version_requires_openssl_102_plus_test_ssl__platform~ubuntu_16.04_auth_ssl~noauth_nossl_python_version~3.7_test_latest_sharded_cluster_patch_fb4c20adfaf64fe9b2a5c8a7f84b2a38dc12d6b2_600081cbe3c3314686c58cf7_21_01_14_17_39_24

is caused by

SERVER-53811 abortTransaction on mongos fails with BSON field 'abortTransaction.recoveryToken' is an unknown field

Closed

Assignee:: Shane Harvey

Reporter:: Shane Harvey

Votes:: 0 Vote for this issue

Watchers:: 1 Start watching this issue

Created:: Jan 14 2021 06:05:45 PM UTC

Updated:: Oct 27 2023 07:49:51 PM UTC

Resolved:: Jan 21 2021 06:06:41 PM UTC

Details

Description

Attachments

Issue Links

Activity

People

Dates