locked
MSDTC inconsistency RRS feed

  • Question

  • hi Community,

    I have this inconsistency issue when calling the layer which is inherited from ServicedComponents to insert values into Oracle database. The web server is running on Win 2K Adv server whereas the oracle is running on Win 2003 SE. I hit one of the following error message when I try to insert/update/delete values into oracle db:

     - The root transaction wanted to commit, but transaction aborted

    - Unable to enlist in the distributed transaction

    This code was working in order since day 1 of implementation but we noticed this inconsistant behavior recently perhaps since Jan 2007 security patch. The first time it's failed but the subsequent attempt to commit succeeded.

    On Win 2003 server, I amended the MSDTC settings as suggested in the florin's article "XP SP2 and Transactions" but the issue still persists.

    Is there any settings that I need to do on Win 2K Adv Server to get rid of the issue?

    Any help is very much appreciated.

    Thanks in advance.

    -Mansoor

    Wednesday, March 14, 2007 12:01 AM

Answers

  • Thanks for the update. Unfortunately this doesn't say much, it doesn't really say something is wrong with MSDTC.

     

    You have a few options:

    - Contact Oracle.DataAccess owners to investigate why they fail to enlist; if they believe it is an MSDTC issue, come back here with the exact error they are getting when they try to enlist

    - See if there is some sort of verbose tracing for Oracle.DataAccess and enable it and see if it tells you more about what function call is failing. Post back the verbose tracing.

     

    Regards,

    Florin

    Tuesday, April 10, 2007 12:26 AM

All replies

  • Hi there,

    Can you try running DTC Ping and post the outputs: http://blogs.msdn.com/florinlazar/archive/2005/09/16/469064.aspx ?

     

    Saturday, March 17, 2007 1:43 AM
  • hi Florin,

    here is the dtcping log:

    Below are the log generated at Web Application Server calling Database server:
    ==============================================================================

     ++++++++++++lmhosts.sam++++++++++++

     ++++++++++++hosts      ++++++++++++
     127.0.0.1       localhost

     ++++++++++++++++++++++++++++++++++++++++++++++
          DTCping 1.9 Report for TTC01WAP5A1 
     ++++++++++++++++++++++++++++++++++++++++++++++
     RPC server is ready
     ++++++++++++Validating Remote Computer Name++++++++++++
     03-20, 17:43:02.243-->Start DTC connection test
     Name Resolution:
      ttc01dba6s-->172.16.172.91-->ttc01dba6s.ttc.tcad-int
     03-20, 17:43:02.243-->Start RPC test (TTC01WAP5A1-->ttc01dba6s)
     RPC test is successful
      Partner's CID:C9A65DE7-D2E0-484C-BFCD-9665AC2D90A1
     ++++++++++++RPC test completed+++++++++++++++
     ++++++++++++Start DTC Binding Test +++++++++++++
     Trying Bind to ttc01dba6s
     03-20, 17:43:02.290-->TTC01WAP5A1 Initiating DTC Binding Test....
      Test Guid:CFB20CB8-08FD-437A-9287-131D23138ED9
     Received reverse bind call from ttc01dba6s
      Network Name: ttc01wap5a1.ttc.tcad-int
      Source  Port: 3543
      Hosting Machine:TTC01WAP5A1
     Binding success: TTC01WAP5A1-->ttc01dba6s
     ++++++++++++DTC Binding Test END+++++++++++++
     03-20, 17:43:09.337-->RPC server:TTC01WAP5A1 received following information:
      Network Name: ttc01wap5a1.ttc.tcad-int
      Source  Port: 3543
      Partner LOG: TTC01DBA6S6040.log
      Partner CID: C9A65DE7-D2E0-484C-BFCD-9665AC2D90A1
     ++++++++++++Start Reverse Bind Test+++++++++++++
     Received Bind call from TTC01DBA6S
      Network Name: ttc01wap5a1.ttc.tcad-int
      Source  Port: 3543
      Hosting Machine:TTC01WAP5A1
     03-20, 17:43:09.430-->Trying to Reverse Bind to TTC01DBA6S...
      Test Guid:C9A65DE7-D2E0-484C-BFCD-9665AC2D90A1
     Name Resolution:
      TTC01DBA6S-->172.16.172.91-->ttc01dba6s.ttc.tcad-int
     Reverse Binding success: TTC01WAP5A1-->TTC01DBA6S
     ++++++++++++Reverse Bind Test ENDED++++++++++

    Below are the log generated at Database server:
    ===============================================

     ++++++++++++lmhosts.sam++++++++++++

     ++++++++++++hosts      ++++++++++++
     127.0.0.1       localhost

     ++++++++++++++++++++++++++++++++++++++++++++++
          DTCping 1.9 Report for TTC01DBA6S 
     ++++++++++++++++++++++++++++++++++++++++++++++
     RPC server is ready
     03-20, 17:43:01.922-->RPC server:TTC01DBA6S received following information:
      Network Name: ttc01dba6s
      Source  Port: 1602
      Partner LOG: TTC01WAP5A13688.log
      Partner CID: CFB20CB8-08FD-437A-9287-131D23138ED9
     ++++++++++++Start Reverse Bind Test+++++++++++++
     Received Bind call from TTC01WAP5A1
      Network Name: ttc01dba6s
      Source  Port: 1602
      Hosting Machine:TTC01DBA6S
     03-20, 17:43:02.032-->Trying to Reverse Bind to TTC01WAP5A1...
      Test Guid:CFB20CB8-08FD-437A-9287-131D23138ED9
     Name Resolution:
      TTC01WAP5A1-->172.16.172.101-->ttc01wap5a1.ttc.tcad-int
     Reverse Binding success: TTC01DBA6S-->TTC01WAP5A1
     ++++++++++++Reverse Bind Test ENDED++++++++++
     03-20, 17:43:02.094-->Called POKE from Partner:TTC01WAP5A1
      Network Name: ttc01dba6s
      Source  Port: 1602
      Hosting Machine:TTC01DBA6S
     ++++++++++++Validating Remote Computer Name++++++++++++
     03-20, 17:43:09.001-->Start DTC connection test
     Name Resolution:
      ttc01wap5a1-->172.16.172.101-->ttc01wap5a1.ttc.tcad-int
     03-20, 17:43:09.001-->Start RPC test (TTC01DBA6S-->ttc01wap5a1)
     RPC test is successful
      Partner's CID:CFB20CB8-08FD-437A-9287-131D23138ED9
     ++++++++++++RPC test completed+++++++++++++++
     ++++++++++++Start DTC Binding Test +++++++++++++
     Trying Bind to ttc01wap5a1
     03-20, 17:43:09.032-->TTC01DBA6S Initiating DTC Binding Test....
      Test Guid:C9A65DE7-D2E0-484C-BFCD-9665AC2D90A1
     Received reverse bind call from ttc01wap5a1
      Network Name: ttc01dba6s
      Source  Port: 1602
      Hosting Machine:TTC01DBA6S
     Binding success: TTC01DBA6S-->ttc01wap5a1
     ++++++++++++DTC Binding Test END+++++++++++++

    Tuesday, March 20, 2007 9:57 AM
  • It looks that the two MSDTC can communicate just fine between them, so the issue seems to be local.

    A few questions:

    - are you saying that only the first call fails and all the subsequent ones are working ok?

    - can you repro the issue consistently or it is intermittent?

    - if you execute the transaction code outside the web server, as a standalone app, do you see the same issue?

    - can you enable CM and transaction tracing on both computers and post back the content of the logs? Follow http://support.microsoft.com/kb/898918 for W2K and http://support.microsoft.com/kb/899115 for W2K3.

     

    Thursday, March 22, 2007 10:27 AM
  • Florin,

    Thanks for your reply.

    q:[- are you saying that only the first call fails and all the subsequent ones are working ok?]

    a: it's not always the first call failed but 3 out of 10 transactions failed on the first attempt.

    q:[- can you repro the issue consistently or it is intermittent?]

    a:  it's intermittent.

    q: [- if you execute the transaction code outside the web server, as a standalone app, do you see the same issue?]

    a: we'll build a standalone exe to test and simulate the error.

    I'll post the tracing details as soon as I got for the servers.

    Thursday, March 22, 2007 11:53 AM
  • Florin,

     

    How many connections can be enlisted in a transaction? Is there a limit to it? Please post details about the enlistment of transactions or refer to any documentation that shares these details.

     

    How do you get rid of the enlistment of transactions if you're running select queries against a database?

     

    Though I do not find any exception from the transaction log, I hereby submit the log for your information.

     

    pid=664        ;tid=3648       ;time=03/27/2007-17:13:10.265 ;seq=117656     ;eventid=TRANSACTION_BEGUN       ;tx_guid=3CFDC1BF-7825-4ACC-8035-F8DD0E691475     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.CommonBF"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:10.265 ;seq=117657     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=3CFDC1BF-7825-4ACC-8035-F8DD0E691475     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:10.265 ;seq=117658     ;eventid=RECEIVED_COMMIT_REQUEST_FROM_BEGINNER  ;tx_guid=3CFDC1BF-7825-4ACC-8035-F8DD0E691475     ;"received request to commit the transaction from beginner"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:10.265 ;seq=117659     ;eventid=TRANSACTION_COMMITTED      ;tx_guid=3CFDC1BF-7825-4ACC-8035-F8DD0E691475     ;"transaction has got committed"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:10.515 ;seq=117660     ;eventid=TRANSACTION_BEGUN       ;tx_guid=63E350A1-4A40-4C61-9551-B4E030084D7F     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.WO.WOIssueBF"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:10.515 ;seq=117661     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=63E350A1-4A40-4C61-9551-B4E030084D7F     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:10.531 ;seq=117662     ;eventid=RECEIVED_COMMIT_REQUEST_FROM_BEGINNER  ;tx_guid=63E350A1-4A40-4C61-9551-B4E030084D7F     ;"received request to commit the transaction from beginner"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:10.531 ;seq=117663     ;eventid=TRANSACTION_COMMITTED      ;tx_guid=63E350A1-4A40-4C61-9551-B4E030084D7F     ;"transaction has got committed"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:10.531 ;seq=117664     ;eventid=TRANSACTION_BEGUN       ;tx_guid=2DC7EEB0-8078-464F-BFED-52B64A5EBFFF     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.WO.WOIssueBF"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:10.547 ;seq=117665     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=2DC7EEB0-8078-464F-BFED-52B64A5EBFFF     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:10.562 ;seq=117666     ;eventid=RECEIVED_COMMIT_REQUEST_FROM_BEGINNER  ;tx_guid=2DC7EEB0-8078-464F-BFED-52B64A5EBFFF     ;"received request to commit the transaction from beginner"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:10.562 ;seq=117667     ;eventid=TRANSACTION_COMMITTED      ;tx_guid=2DC7EEB0-8078-464F-BFED-52B64A5EBFFF     ;"transaction has got committed"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:18.062 ;seq=117668     ;eventid=TRANSACTION_BEGUN       ;tx_guid=5E4A9F58-1B2D-41A0-9B2A-D05E37427031     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.RCCSInterfaceBF"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:18.062 ;seq=117669     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=5E4A9F58-1B2D-41A0-9B2A-D05E37427031     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:25.093 ;seq=117670     ;eventid=RECEIVED_COMMIT_REQUEST_FROM_BEGINNER  ;tx_guid=5E4A9F58-1B2D-41A0-9B2A-D05E37427031     ;"received request to commit the transaction from beginner"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:25.093 ;seq=117671     ;eventid=TRANSACTION_COMMITTED      ;tx_guid=5E4A9F58-1B2D-41A0-9B2A-D05E37427031     ;"transaction has got committed"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:13.093 ;seq=117672     ;eventid=TRANSACTION_BEGUN       ;tx_guid=FF59F071-583C-41EC-815B-0E7007A13EF1     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.PIS.StreetReade"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:13.093 ;seq=117673     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=FF59F071-583C-41EC-815B-0E7007A13EF1     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:25.172 ;seq=117674     ;eventid=RECEIVED_COMMIT_REQUEST_FROM_BEGINNER  ;tx_guid=FF59F071-583C-41EC-815B-0E7007A13EF1     ;"received request to commit the transaction from beginner"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:25.172 ;seq=117675     ;eventid=TRANSACTION_COMMITTED      ;tx_guid=FF59F071-583C-41EC-815B-0E7007A13EF1     ;"transaction has got committed"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:25.109 ;seq=117676     ;eventid=TRANSACTION_BEGUN       ;tx_guid=D0F62EC7-13A9-4523-A896-AA3E220C256E     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.CIS.AccountDeta"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:25.109 ;seq=117677     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=D0F62EC7-13A9-4523-A896-AA3E220C256E     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:25.453 ;seq=117678     ;eventid=RECEIVED_COMMIT_REQUEST_FROM_BEGINNER  ;tx_guid=D0F62EC7-13A9-4523-A896-AA3E220C256E     ;"received request to commit the transaction from beginner"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:25.453 ;seq=117679     ;eventid=TRANSACTION_COMMITTED      ;tx_guid=D0F62EC7-13A9-4523-A896-AA3E220C256E     ;"transaction has got committed"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:27.562 ;seq=117680     ;eventid=TRANSACTION_BEGUN       ;tx_guid=8F1E0C81-CBD0-4389-8EDE-EABB8D8C21D6     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.CIS.AccountDeta"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:27.562 ;seq=117681     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=8F1E0C81-CBD0-4389-8EDE-EABB8D8C21D6     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:27.859 ;seq=117682     ;eventid=RECEIVED_COMMIT_REQUEST_FROM_BEGINNER  ;tx_guid=8F1E0C81-CBD0-4389-8EDE-EABB8D8C21D6     ;"received request to commit the transaction from beginner"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:27.859 ;seq=117683     ;eventid=TRANSACTION_COMMITTED      ;tx_guid=8F1E0C81-CBD0-4389-8EDE-EABB8D8C21D6     ;"transaction has got committed"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:27.953 ;seq=117684     ;eventid=TRANSACTION_BEGUN       ;tx_guid=9977CA5B-F33E-4030-A365-48D556DC215A     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.CIS.AccountDeta"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:27.953 ;seq=117685     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=9977CA5B-F33E-4030-A365-48D556DC215A     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:28.203 ;seq=117686     ;eventid=RECEIVED_ABORT_REQUEST_FROM_BEGINNER  ;tx_guid=9977CA5B-F33E-4030-A365-48D556DC215A     ;"received request to abort the transaction from beginner"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:28.203 ;seq=117687     ;eventid=TRANSACTION_ABORTING      ;tx_guid=9977CA5B-F33E-4030-A365-48D556DC215A     ;"transaction is aborting"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:28.203 ;seq=117688     ;eventid=RM_ISSUED_ABORT        ;tx_guid=9977CA5B-F33E-4030-A365-48D556DC215A     ;"abort request issued to resource manager #1 for transaction enlistment #1002"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:28.203 ;seq=117689     ;eventid=RM_ACKNOWLEDGED_ABORT      ;tx_guid=9977CA5B-F33E-4030-A365-48D556DC215A     ;"received acknowledgement of abort request from the resource manager #1 for transaction enlistment #1002"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:28.203 ;seq=117690     ;eventid=TRANSACTION_ABORTED       ;tx_guid=9977CA5B-F33E-4030-A365-48D556DC215A     ;"transaction has got aborted"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:32.297 ;seq=117691     ;eventid=TRANSACTION_BEGUN       ;tx_guid=0C32C102-F046-4B51-B502-438A0368D30D     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.CIS.AccountDeta"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:32.297 ;seq=117692     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=0C32C102-F046-4B51-B502-438A0368D30D     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:32.375 ;seq=117693     ;eventid=RECEIVED_ABORT_REQUEST_FROM_BEGINNER  ;tx_guid=0C32C102-F046-4B51-B502-438A0368D30D     ;"received request to abort the transaction from beginner"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:32.375 ;seq=117694     ;eventid=TRANSACTION_ABORTING      ;tx_guid=0C32C102-F046-4B51-B502-438A0368D30D     ;"transaction is aborting"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:32.375 ;seq=117695     ;eventid=RM_ISSUED_ABORT        ;tx_guid=0C32C102-F046-4B51-B502-438A0368D30D     ;"abort request issued to resource manager #1 for transaction enlistment #1002"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:32.375 ;seq=117696     ;eventid=RM_ACKNOWLEDGED_ABORT      ;tx_guid=0C32C102-F046-4B51-B502-438A0368D30D     ;"received acknowledgement of abort request from the resource manager #1 for transaction enlistment #1002"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:32.375 ;seq=117697     ;eventid=TRANSACTION_ABORTED       ;tx_guid=0C32C102-F046-4B51-B502-438A0368D30D     ;"transaction has got aborted"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:36.203 ;seq=117698     ;eventid=TRANSACTION_BEGUN       ;tx_guid=BC594154-57B7-4330-9BE4-2626FE0531F0     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.CIS.AccountDeta"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:36.203 ;seq=117699     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=BC594154-57B7-4330-9BE4-2626FE0531F0     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:36.484 ;seq=117700     ;eventid=RECEIVED_COMMIT_REQUEST_FROM_BEGINNER  ;tx_guid=BC594154-57B7-4330-9BE4-2626FE0531F0     ;"received request to commit the transaction from beginner"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:36.484 ;seq=117701     ;eventid=TRANSACTION_COMMITTED      ;tx_guid=BC594154-57B7-4330-9BE4-2626FE0531F0     ;"transaction has got committed"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:36.640 ;seq=117702     ;eventid=TRANSACTION_BEGUN       ;tx_guid=B0BD59C0-32A9-4132-8405-2E3BB2CD1595     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.CIS.AccountDeta"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:36.640 ;seq=117703     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=B0BD59C0-32A9-4132-8405-2E3BB2CD1595     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:36.953 ;seq=117704     ;eventid=RECEIVED_COMMIT_REQUEST_FROM_BEGINNER  ;tx_guid=B0BD59C0-32A9-4132-8405-2E3BB2CD1595     ;"received request to commit the transaction from beginner"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:36.953 ;seq=117705     ;eventid=TRANSACTION_COMMITTED      ;tx_guid=B0BD59C0-32A9-4132-8405-2E3BB2CD1595     ;"transaction has got committed"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:44.422 ;seq=117706     ;eventid=TRANSACTION_BEGUN       ;tx_guid=013F46BA-1A54-4A5C-BA3D-7F9923CB5681     ;"transaction got begun, description : NCS.TCMS.BusinessFacade.CIS.MemoTypeBF"
    pid=664        ;tid=2216       ;time=03/27/2007-17:13:44.422 ;seq=117707     ;eventid=RM_ENLISTED_IN_TRANSACTION     ;tx_guid=013F46BA-1A54-4A5C-BA3D-7F9923CB5681     ;"resource manager #1 enlisted as transaction enlistment #1002. RM guid = {A2FC06D1-9E33-452F-A6A9-4E96EB00E222}"
    pid=664        ;tid=3080       ;time=03/27/2007-17:13:44.422 ;seq=117708     ;eventid=RECEIVED_COMMIT_REQUEST_FROM_BEGINNER  ;tx_guid=013F46BA-1A54-4A5C-BA3D-7F9923CB5681     ;"received request to commit the transaction from beginner"
    pid=664        ;tid=3648       ;time=03/27/2007-17:13:44.422 ;seq=117709     ;eventid=TRANSACTION_COMMITTED      ;tx_guid=013F46BA-1A54-4A5C-BA3D-7F9923CB5681     ;"transaction has got committed"

    Wednesday, March 28, 2007 7:35 AM
  • I believe there is a limit of 32 or 64 enlisments in the same transactions but I'm not sure.

     

    In the same time, I don't believe the limit is related to the issue you are seeing. All the logs indicate that MSDTC is doing fine.

     

    The client or the ServicedComponent that initiated the transaction is aborting it for some reason:

    pid=664        ;tid=2216       ;time=03/27/2007-17:13:28.203 ;seq=117686     ;eventid=RECEIVED_ABORT_REQUEST_FROM_BEGINNER  ;tx_guid=9977CA5B-F33E-4030-A365-48D556DC215A     ;"received request to abort the transaction from beginner"

     

    Can you post the exact details on the errors you receive? Are they return values from some APIs? Is there an exception being thrown - if yes, can you post the full details of the exception, including the exception stack?

    Friday, March 30, 2007 1:47 AM
  • Florin,

     

    Here is the complete stack trace of the exception:

     

    Message: Exception of type System.Web.HttpUnhandledException was thrown.
    Source: System.Web
    TargetSite: Boolean HandleError(System.Exception)
    StackTrace:    at System.Web.UI.Page.HandleError(Exception e)
       at System.Web.UI.Page.ProcessRequestMain()
       at System.Web.UI.Page.ProcessRequest()
       at System.Web.UI.Page.ProcessRequest(HttpContext context)
       at System.Web.CallHandlerExecutionStep.System.Web.HttpApplication+IExecutionStep.Execute()
       at System.Web.HttpApplication.ExecuteStep(IExecutionStep step, Boolean& completedSynchronously)

     

    Message: Unable to enlist in a distributed transaction
    Source: Oracle Data Provider for .NET
    TargetSite: Void HandleReturnMessage(System.Runtime.Remoting.Messaging.IMessage, System.Runtime.Remoting.Messaging.IMessage)
    StackTrace:
    Server stack trace:
       at Oracle.DataAccess.Client.OracleException.HandleErrorHelper(Int32 errCode, OracleConnection conn, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, String procedure)
       at Oracle.DataAccess.Client.OracleConnection.Open()
       at NCS.TCMS.DataAccess.DataAccessBase..ctor()
       at NCS.TCMS.DataAccess.RCCSInterfaceDA..ctor()
       at NCS.TCMS.BusinessRules.RCCSInterfaceBR.GetRCCSAccount(String tcAccNo)
       at NCS.TCMS.BusinessFacade.RCCSInterfaceBF.IsRCCSAccount(String tcAccountNo)
       at System.Runtime.Remoting.Messaging.Message.Dispatch(Object target, Boolean fExecuteInContext)
       at System.Runtime.Remoting.Messaging.StackBuilderSink.SyncProcessMessage(IMessage msg, Int32 methodPtr, Boolean fExecuteInContext)

    Exception rethrown at [0]:
       at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessage(IMessage reqMsg, IMessage retMsg)
       at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(MessageData& msgData, Int32 type)
       at NCS.TCMS.BusinessFacade.RCCSInterfaceBF.IsRCCSAccount(String tcAccountNo)
       at NCS.TCMS.BusinessFacade.CISInterfaceReaderBF.GetPayBy(String tcAccNo)
       at NCS.TCMS.WebUI.CIS.Query.AccountDetailsSearch.PageBind()
       at NCS.TCMS.WebUI.CIS.Query.AccountDetailsSearch.Page_Load(Object sender, EventArgs e)
       at System.Web.UI.Control.OnLoad(EventArgs e)
       at NCS.TCMS.WebUI.PageBase.OnLoad(EventArgs e)
       at System.Web.UI.Control.LoadRecursive()
       at System.Web.UI.Page.ProcessRequestMain()

     

    Sunday, April 8, 2007 9:55 AM
  • Thanks for the update. Unfortunately this doesn't say much, it doesn't really say something is wrong with MSDTC.

     

    You have a few options:

    - Contact Oracle.DataAccess owners to investigate why they fail to enlist; if they believe it is an MSDTC issue, come back here with the exact error they are getting when they try to enlist

    - See if there is some sort of verbose tracing for Oracle.DataAccess and enable it and see if it tells you more about what function call is failing. Post back the verbose tracing.

     

    Regards,

    Florin

    Tuesday, April 10, 2007 12:26 AM