Uploaded image for project: 'QuickFIX/J'
  1. QuickFIX/J
  2. QFJ-619

Late Resend Request causes sequence number mayhem : what happened?

    Details

    • Type: Other
    • Status: Open
    • Priority: Default
    • Resolution: Unresolved
    • Affects Version/s: 1.4.0
    • Fix Version/s: None
    • Component/s: Engine
    • Labels:
      None

      Description

      Hello everyone,

      A client seems to have a problem that I cannot reproduce. Basically, the third party asks for a resend request of a message sent 7 minutes earlier. During those 7 mintures, heartbeats have been exchanged and the sequence increased. When the Resend Request is received, this happens (look at the sequence numbers).
      Is it a known bug in QuickFIX/J 1.4.0 ? fixed in 1.5.0 ?

      Thanks, best regards,

      Nicolas

      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:30:40.704" level="Enabled" thread="QFJ Timer">
      <fix:message length="79" way="outgoing"><![CDATA[8=FIX.4.4|9=57|35=0|34=260|49=NOYOBU|52=20110705-11:30:40.704|56=NOYOBU|10=215|]]></fix:message>
      </fix:trace>

      [... nothing of importance, just heartbeats in and out for 7 minutes...]

      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:37:46.695" level="Enabled" thread="QFJ Timer">
      <fix:message length="79" way="outgoing"><![CDATA[8=FIX.4.4|9=57|35=0|34=267|49=NOYOBU|52=20110705-11:37:46.695|56=NOYOBU|10=244|]]></fix:message>
      </fix:trace>

      [... here seqnum is 267, right? ...]

      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:37:46.788" level="Enabled" thread="SocketConnectorIoProcessor-0.0">
      <fix:message length="88" way="incoming"><![CDATA[8=FIX.4.4|9=0064|35=2|49=NOYOBU|56=NOYOBU|34=269|52=20110705-11:37:46|7=260|16=0|10=102|]]></fix:message>
      </fix:trace>
      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:37:46.788" level="Enabled" thread="QFJ Message Processor">
      <fix:event length="45"><![CDATA[Received ResendRequest FROM: 260 TO: infinity]]></fix:event>
      </fix:trace>

      [... counterparty asks for 260, sent 7 minutes earlier ...]

      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:37:47.185" level="Enabled" thread="QFJ Message Processor">
      <fix:message length="119" way="outgoing"><![CDATA[8=FIX.4.4|9=97|35=4|34=260|43=Y|49=NOYOBU|52=20110705-11:37:47.185|56=NOYOBU|122=20110705-11:37:47|36=261|123=Y|10=150|]]></fix:message>
      </fix:trace>
      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:37:47.186" level="Enabled" thread="QFJ Message Processor">
      <fix:event length="26"><![CDATA[Sent SequenceReset TO: 261]]></fix:event>
      </fix:trace>

      [... sending 260, reset to 261 ?? ...]

      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:37:47.186" level="Enabled" thread="QFJ Message Processor">
      <fix:message length="119" way="outgoing"><![CDATA[8=FIX.4.4|9=97|35=4|34=260|43=Y|49=NOYOBU|52=20110705-11:37:47.186|56=NOYOBU|122=20110705-11:37:47|36=267|123=Y|10=157|]]></fix:message>
      </fix:trace>
      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:37:47.186" level="Enabled" thread="QFJ Message Processor">
      <fix:event length="26"><![CDATA[Sent SequenceReset TO: 267]]></fix:event>
      </fix:trace>

      [... sending 260 AGAIN, reset to 267 ????? ...]

      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:38:46.819" level="Enabled" thread="SocketConnectorIoProcessor-0.0">
      <fix:message length="77" way="incoming"><![CDATA[8=FIX.4.4|9=0053|35=0|49=NOYOBU|56=NOYOBU|34=270|52=20110705-11:38:46|10=121|]]></fix:message>
      </fix:trace>
      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:38:47.699" level="Enabled" thread="QFJ Timer">
      <fix:message length="79" way="outgoing"><![CDATA[8=FIX.4.4|9=57|35=0|34=268|49=NOYOBU|52=20110705-11:38:47.698|56=NOYOBU|10=250|]]></fix:message>
      </fix:trace>
      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:38:47.831" level="Enabled" thread="SocketConnectorIoProcessor-0.0">
      <fix:message length="88" way="incoming"><![CDATA[8=FIX.4.4|9=0064|35=2|49=NOYOBU|56=NOYOBU|34=271|52=20110705-11:38:47|7=261|16=0|10=098|]]></fix:message>
      </fix:trace>

      [... and it goes on and on and on and on...]

      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:38:47.831" level="Enabled" thread="QFJ Message Processor">
      <fix:event length="45"><![CDATA[Received ResendRequest FROM: 261 TO: infinity]]></fix:event>
      </fix:trace>
      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:38:48.187" level="Enabled" thread="QFJ Message Processor">
      <fix:message length="119" way="outgoing"><![CDATA[8=FIX.4.4|9=97|35=4|34=261|43=Y|49=NOYOBU|52=20110705-11:38:48.187|56=NOYOBU|122=20110705-11:38:48|36=262|123=Y|10=158|]]></fix:message>
      </fix:trace>
      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:38:48.188" level="Enabled" thread="QFJ Message Processor">
      <fix:event length="26"><![CDATA[Sent SequenceReset TO: 262]]></fix:event>
      </fix:trace>
      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:38:48.188" level="Enabled" thread="QFJ Message Processor">
      <fix:message length="119" way="outgoing"><![CDATA[8=FIX.4.4|9=97|35=4|34=261|43=Y|49=NOYOBU|52=20110705-11:38:48.188|56=NOYOBU|122=20110705-11:38:48|36=268|123=Y|10=165|]]></fix:message>
      </fix:trace>
      <fix:trace logger="fix-engine-05301" timestamp="2011-07-05 13:38:48.188" level="Enabled" thread="QFJ Message Processor">
      <fix:event length="26"><![CDATA[Sent SequenceReset TO: 268]]></fix:event>
      </fix:trace>

        Attachments

          Activity

            People

            • Assignee:
              Unassigned
              Reporter:
              nicolas_chaufette Nicolas Chaufette
            • Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

              Dates

              • Created:
                Updated: