Transaction CXX library sometimes incorrectly creates documents in default collection

Hi David, 

CB: 7.0.0 build 4554

TXNCC:CXX_2.0.0

TestName: transactionSupportsMaxCollections

 

This test is failing once in a while on jenkins. When debugged, I could find that sometimes the CXX library is creating the document in the default collection of the bucket and ignoring the custom collection in which the document is supposed to be created.

 

 

Logs:

[19:02:56.223][fit_cxx][trace][t:5301472] transactionSupportsMaxCollections:  executing command: insert {

  docId

{     bucketName: "default"     scopeName: "customScope"     collectionName: "customCollectionInCustomScope0"     docId: "4d893c76-878f-442e-87b3-123b7477122e"   }

  contentJson: "{\"content\":\"initial\"}"

  expectedResult

{     success: true   }

}

 

[19:02:56.223][fit_cxx][trace][t:5301472] transactionSupportsMaxCollections:  executing insert {

  docId

{     bucketName: "default"     scopeName: "customScope"     collectionName: "customCollectionInCustomScope0"     docId: "4d893c76-878f-442e-87b3-123b7477122e"   }

  contentJson: "{\"content\":\"initial\"}"

  expectedResult

{     success: true   }

}

 

[19:02:56.223][fit_cxx][trace][t:5301472] transactionSupportsMaxCollections:  calling op

[19:02:56.223][transactions][info][t:5301472] [e134caee-abc3-475d-bb8d-78c5630fb37a/8db5c703-b23b-4645-baa8-e56037ca1836]:first mutated doc in transaction is "4d893c76-878f-442e-87b3-123b7477122e" on vbucket 356, so using atr "_txn:atr-356-#2f"

[19:02:56.223][transactions][info][t:5301472] [e134caee-abc3-475d-bb8d-78c5630fb37a/8db5c703-b23b-4645-baa8-e56037ca1836]:updating atr _txn:atr-356-#2f

[19:02:56.349][client][trace][t:5301472] mutate_in returning result{rc:0,strerror:LCB_SUCCESS (0),cas:1614913376334905344,is_deleted:0,datatype:,flags:0,values:[

{,0},{,0},{,0},{,0},]}

[19:02:56.349][transactions][info][t:5301472] [e134caee-abc3-475d-bb8d-78c5630fb37a/8db5c703-b23b-4645-baa8-e56037ca1836]:set ATR default/customCollectionInCustomScope0/_txn:atr-356-#2f to Pending, got CAS (start time) 1614913376334905344

[19:02:56.349][transactions][info][t:5301472] [e134caee-abc3-475d-bb8d-78c5630fb37a/8db5c703-b23b-4645-baa8-e56037ca1836]:about to insert staged doc 4d893c76-878f-442e-87b3-123b7477122e with cas 0

[19:02:56.366][client][trace][t:5301472] mutate_in returning result{rc:0,strerror:LCB_SUCCESS (0),cas:1614913376352337920,is_deleted:1,datatype:,flags:0,values:[{,0}

,{,0},{,0},{,0},{,0},{,0},{,0},{,0},]}

[19:02:56.366][transactions][info][t:5301472] [e134caee-abc3-475d-bb8d-78c5630fb37a/8db5c703-b23b-4645-baa8-e56037ca1836]:inserted doc 4d893c76-878f-442e-87b3-123b7477122e CAS=1614913376352337920, rc=LCB_SUCCESS (0)

[19:02:56.366][fit_cxx][trace][t:5301472] transactionSupportsMaxCollections:  attempt 1 8db5c703-b23b-4645-baa8-e56037ca1836 complete

[19:02:56.366][transactions][info][t:5301472] [e134caee-abc3-475d-bb8d-78c5630fb37a/8db5c703-b23b-4645-baa8-e56037ca1836]:commit 8db5c703-b23b-4645-baa8-e56037ca1836

[19:02:56.383][client][trace][t:5301472] mutate_in returning result{rc:0,strerror:LCB_SUCCESS (0),cas:1614913376369180672,is_deleted:0,datatype:,flags:0,values:[

{,0},{,0},{,0},{,0},{,0},]}

[19:02:56.383][transactions][trace][t:5301472] [e134caee-abc3-475d-bb8d-78c5630fb37a/8db5c703-b23b-4645-baa8-e56037ca1836]:commit doc 4d893c76-878f-442e-87b3-123b7477122e, cas_zero_mode false, ambiguity_resolution_mode false

[19:02:56.383][transactions][trace][t:5301472] [e134caee-abc3-475d-bb8d-78c5630fb37a/8db5c703-b23b-4645-baa8-e56037ca1836]:commit doc id 4d893c76-878f-442e-87b3-123b7477122e, content {"content":"initial"}, cas 1614913376352337920

[19:02:56.398][client][trace][t:5301472] store_callback returning result{rc:0,strerror:LCB_SUCCESS (0),cas:1614913376385433600,is_deleted:0,datatype:,flags:0}

[19:02:56.398][transactions][trace][t:5301472] [e134caee-abc3-475d-bb8d-78c5630fb37a/8db5c703-b23b-4645-baa8-e56037ca1836]:commit doc result result{rc:0,strerror:LCB_SUCCESS (0),cas:1614913376385433600,is_deleted:0,datatype:,flags:0}

[19:02:56.398][transactions][trace][t:5301472] [e134caee-abc3-475d-bb8d-78c5630fb37a/8db5c703-b23b-4645-baa8-e56037ca1836]:removing attempt _txn:atr-356-#2f from atr

[19:02:56.417][client][trace][t:5301472] mutate_in returning result{rc:0,strerror:LCB_SUCCESS (0),cas:1614913376402079744,is_deleted:0,datatype:,flags:0,values:[{,0}

,{,0},]}

[19:02:56.417][attempt_cleanup][trace][t:5301472] attempt in state COMPLETED, not adding to cleanup

[19:02:56.417][fit_cxx][trace][t:5301472] transactionSupportsMaxCollections:  response: transactionId: "e134caee-abc3-475d-bb8d-78c5630fb37a"

unstagingComplete: true

 

[19:02:56.423][fit_cxx][trace][t:5301282] Closing transactionsFactoryRef cb7e4b58-c695-daeb-8d69-cb7522a306c1