4 Replies Latest reply on Feb 24, 2020 6:12 PM by user9130018

    Response delay

    2668757

      Hi, friends

      My probelam is as follows:
      I have two services.
      Service A inserts account movements record in database table 1 and inserts record with transaction details in table 2
      Service B is the reverse of the previous service, that is, it deletes the movement in table 1 and update transaction status in table 2
      First I invoke service A and after 2 seconds service B.
      What is observed is that it cannot delete the movement that has inserted by service A nor change the status.on table 2
      Anyone know if there is a latency in the tuxedo response

       

      Best regards

        • 1. Re: Response delay
          2668757

          The trace for XA is:

           

          125153.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 x9d:   TRACE:xa:  { xa_end(0x0x9682920, 0, 0x4000000)

          125153.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 x9d:   TRACE:xa:  } xa_end = 0

          125153.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 x9d:   TRACE:xa:  { xa_commit(0x0x9682920, 0, 0x40000000)

          125153.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 x9d:   TRACE:xa:  } xa_commit = 0

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  { LDC(0x0x8c03920, 0, 0x0)

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  } LDC = 0

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  { xa_end(0x8c03920, 0, 0x2000000)

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  } xa_end = 0

          125154.SVR32286!ahorroPlazo.4910.6300640.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  { L4#(0x0x9df8920, 0, 0x0)

          125154.SVR32286!ahorroPlazo.4910.6300640.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  } L4# = 0

          125154.SVR32286!ahorroPlazo.4910.6300640.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  { xa_end(0x0x9df8920, 0, 0x4000000)

          125154.SVR32286!ahorroPlazo.4910.6300640.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  } xa_end = 0

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  { xa_start(0x8c03920, 0, 0x8000000)

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  } xa_start = 0

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  { xa_end(0x8c03920, 0, 0x2000000)

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  } xa_end = 0

          125154.SVR32286!ahorroAutorizador.4919.3007360.0: gtrid x0 x5e4ec605 x9e:       TRACE:xa:  { L4#(0x0x95a5920, 0, 0x200000)

          125154.SVR32286!ahorroAutorizador.4919.3007360.0: gtrid x0 x5e4ec605 x9e:       TRACE:xa:  } L4# = 0

          125154.SVR32286!ahorroAutorizador.4919.3007360.0: gtrid x0 x5e4ec605 x9e:       TRACE:xa:  { xa_end(0x0x95a5920, 0, 0x4000000)

          125154.SVR32286!ahorroAutorizador.4919.3007360.0: gtrid x0 x5e4ec605 x9e:       TRACE:xa:  } xa_end = 0

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  { xa_start(0x8c03920, 0, 0x8000000)

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  } xa_start = 0

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  { xa_end(0x8c03920, 0, 0x2000000)

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  } xa_end = 0

          125154.SVR32286!ahorroPlazo.4911.1516512.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  { L▒@(0x0x9f19920, 0, 0x200000)

          125154.SVR32286!ahorroPlazo.4911.1516512.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  } L▒@ = 0

          125154.SVR32286!ahorroPlazo.4911.1516512.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  { xa_end(0x9f19920, 0, 0x2000000)

          125154.SVR32286!ahorroPlazo.4911.1516512.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  } xa_end = 0

          125154.SVR32286!cliente.4813.4817856.0: gtrid x0 x5e4ec605 x9e: TRACE:xa:  { L▒>(0x0x9f91920, 0, 0x0)

          125154.SVR32286!cliente.4813.4817856.0: gtrid x0 x5e4ec605 x9e: TRACE:xa:  } L▒> = 0

          125154.SVR32286!cliente.4813.4817856.0: gtrid x0 x5e4ec605 x9e: TRACE:xa:  { xa_end(0x0x9f91920, 0, 0x4000000)

          125154.SVR32286!cliente.4813.4817856.0: gtrid x0 x5e4ec605 x9e: TRACE:xa:  } xa_end = 0

          125154.SVR32286!ahorroPlazo.4911.1516512.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  { xa_start(0x9f19920, 0, 0x8000000)

          125154.SVR32286!ahorroPlazo.4911.1516512.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  } xa_start = 0

          125154.SVR32286!ahorroPlazo.4911.1516512.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  { xa_end(0x0x9f19920, 0, 0x4000000)

          125154.SVR32286!ahorroPlazo.4911.1516512.0: gtrid x0 x5e4ec605 x9e:     TRACE:xa:  } xa_end = 0

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  { xa_start(0x8c03920, 0, 0x8000000)

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  } xa_start = 0

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  { xa_end(0x0x8c03920, 0, 0x4000000)

          125154.SVR32286!tarjetaDebitoAutorizador.4927.2885632.0: gtrid x0 x5e4ec605 x9e:        TRACE:xa:  } xa_end = 0

          125155.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 x9f:   TRACE:xa:  { xa_start(0x0x9682920, 0, 0x0)

          125155.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 x9f:   TRACE:xa:  } xa_start = 0

          125155.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 x9f:   TRACE:xa:  { xa_end(0x0x9682920, 0, 0x4000000)

          125155.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 x9f:   TRACE:xa:  } xa_end = 0

          125155.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 x9f:   TRACE:xa:  { xa_commit(0x0x9682920, 0, 0x40000000)

          125155.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 x9f:   TRACE:xa:  } xa_commit = 0

          125156.SVR32286!tarjetaDebitoAutorizador.4928.2717456.0: gtrid x0 x5e4ec605 xa0:        TRACE:xa:  { xa_start(0x0x8128920, 0, 0x0)

          125156.SVR32286!tarjetaDebitoAutorizador.4928.2717456.0: gtrid x0 x5e4ec605 xa0:        TRACE:xa:  } xa_start = 0

          125156.SVR32286!tarjetaDebitoAutorizador.4928.2717456.0: gtrid x0 x5e4ec605 xa0:        TRACE:xa:  { xa_end(0x8128920, 0, 0x2000000)

          125156.SVR32286!tarjetaDebitoAutorizador.4928.2717456.0: gtrid x0 x5e4ec605 xa0:        TRACE:xa:  } xa_end = 0

          125156.SVR32286!ahorroAutorizador.4920.1365696.0: gtrid x0 x5e4ec605 xa0:       TRACE:xa:  { L▒%(0x0x95ab920, 0, 0x0)

          125156.SVR32286!ahorroAutorizador.4920.1365696.0: gtrid x0 x5e4ec605 xa0:       TRACE:xa:  } L▒% = 0

          125156.SVR32286!ahorroAutorizador.4920.1365696.0: gtrid x0 x5e4ec605 xa0:       TRACE:xa:  { xa_end(0x0x95ab920, 0, 0x4000000)

          125156.SVR32286!ahorroAutorizador.4920.1365696.0: gtrid x0 x5e4ec605 xa0:       TRACE:xa:  } xa_end = 0

          125156.SVR32286!tarjetaDebitoAutorizador.4928.2717456.0: gtrid x0 x5e4ec605 xa0:        TRACE:xa:  { xa_start(0x8128920, 0, 0x8000000)

          125156.SVR32286!tarjetaDebitoAutorizador.4928.2717456.0: gtrid x0 x5e4ec605 xa0:        TRACE:xa:  } xa_start = 0

          125156.SVR32286!tarjetaDebitoAutorizador.4929.1139936.0: gtrid x0 x5e4ec605 xa1:        TRACE:xa:  { xa_start(0x0x98a8920, 0, 0x0)

          125156.SVR32286!tarjetaDebitoAutorizador.4929.1139936.0: gtrid x0 x5e4ec605 xa1:        TRACE:xa:  } xa_start = 0

          125156.SVR32286!tarjetaDebitoAutorizador.4929.1139936.0: gtrid x0 x5e4ec605 xa1:        TRACE:xa:  { xa_end(0x0x98a8920, 0, 0x4000000)

          125156.SVR32286!tarjetaDebitoAutorizador.4929.1139936.0: gtrid x0 x5e4ec605 xa1:        TRACE:xa:  } xa_end = 0

          125156.SVR32286!tarjetaDebitoAutorizador.4929.1139936.0: gtrid x0 x5e4ec605 xa1:        TRACE:xa:  { xa_commit(0x0x98a8920, 0, 0x40000000)

          125156.SVR32286!tarjetaDebitoAutorizador.4929.1139936.0: gtrid x0 x5e4ec605 xa1:        TRACE:xa:  } xa_commit = 0

          125156.SVR32286!tarjetaDebitoAutorizador.4928.2717456.0: gtrid x0 x5e4ec605 xa0:        TRACE:xa:  { xa_end(0x0x8128920, 0, 0x20000000)

          125156.SVR32286!tarjetaDebitoAutorizador.4928.2717456.0: gtrid x0 x5e4ec605 xa0:        TRACE:xa:  } xa_end = 0

          125156.SVR32286!TMS_Oracle10.2.4923.1405872.0: gtrid x0 x5e4ec605 xa0:  TRACE:xa:  { xa_rollback(0x0x8f34920, 0, 0x0)

          125156.SVR32286!TMS_Oracle10.2.4907.3191728.0: gtrid x0 x5e4ec605 xa0:  TRACE:xa:  { xa_rollback(0x0x91fd920, 0, 0x0)

          125156.SVR32286!TMS_Oracle10.2.4923.1405872.0: gtrid x0 x5e4ec605 xa0:  TRACE:xa:  } xa_rollback = 0

          125156.SVR32286!TMS_Oracle10.2.4907.3191728.0: gtrid x0 x5e4ec605 xa0:  TRACE:xa:  } xa_rollback = 0

          125156.SVR32286!TMS_Oracle10.2.4924.1893296.0: gtrid x0 x5e4ec605 x9e:  TRACE:xa:  { xa_prepare(0x0x9d79920, 0, 0x0)

          125156.SVR32286!TMS_Oracle10.2.4811.3081136.0: gtrid x0 x5e4ec605 x9e:  TRACE:xa:  { xa_prepare(0x0x8fc2920, 0, 0x0)

          125156.SVR32286!TMS_Oracle10.2.4924.1893296.0: gtrid x0 x5e4ec605 x9e:  TRACE:xa:  } xa_prepare = 3

          125156.SVR32286!TMS_Oracle10.2.4811.3081136.0: gtrid x0 x5e4ec605 x9e:  TRACE:xa:  } xa_prepare = 3

          125156.SVR32286!TMS_Oracle10.2.4908.4740016.0: gtrid x0 x5e4ec605 x9e:  TRACE:xa:  { xa_prepare(0x0x9fc7920, 0, 0x0)

          125156.SVR32286!TMS_Oracle10.2.4908.4740016.0: gtrid x0 x5e4ec605 x9e:  TRACE:xa:  } xa_prepare = 0

          125156.SVR32286!TMS_Oracle10.2.4909.1405792.0: gtrid x0 x5e4ec605 x9e:  TRACE:xa:  { xa_commit(0x0x9b14920, 0, 0x10000000)

          125156.SVR32286!TMS_Oracle10.2.4909.1405792.0: gtrid x0 x5e4ec605 x9e:  TRACE:xa:  } xa_commit = 0

          125157.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 xa2:   TRACE:xa:  { xa_start(0x0x9682920, 0, 0x0)

          125157.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 xa2:   TRACE:xa:  } xa_start = 0

          125157.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 xa2:   TRACE:xa:  { xa_end(0x9682920, 0, 0x2000000)

          125157.SVR32286!TMQFORWARD.5041.3086829776.0: gtrid x0 x5e4ec605 xa2:   TRACE:xa:  } xa_end = 0

          • 2. Re: Response delay
            user9130018

            Hello

             

            I assume that you call service A and service B in different global transaction or that each of them has AUTOTRAN=Y and runs in dedicated transaction. Is it correct?

            Are you only table1 and table2 user at the time of test? Is is really simple call to service A and and service B and nothing in between? no other calls from other users?

            What exact error do you end up with? deadlock? some specific oracle error code? Is your situation 100% reproducible?

             

            Do you actually have tpcommit after first service A? Can you check database locks after call to service A?

             

            Best Regards

            • 3. Re: Response delay
              2668757

              Hi

               

              I have a tpcommit at the end of each service.At database level, it seems that commitment never did. There is not any Oracle error

              • 4. Re: Response delay
                user9130018

                What's your tuxedo version and what patch do you have installed? There was once error with silent transaction rollback involving services with AUTOTRAN, two independent transactions and tpacall

                 

                Could you answer my previous questions?