Details
Description
Currently our prod env occur an issue about FIX Resend.
1) Before 03:31:16.621, our quickfixj was disconnected by upstream fix engine.
2) On 03:31:16.621, we re-connected.
3) On 03:31:16.839, we(CT) send Resend Request with tag7=23152 and tag16=0.
4) On 03:31:17.541, our customer returned from 23152 to 23974, then they send us 34=23977 and 34=23978, but SKIPPED 23975 and 23976.
5) I found our quickfix found 23974 and 23975 from its own memory queue at the same time, but not found 23976. So our sides occur 'MsgSeqNum too high, expecting 23976 but received 23977', cannot receive subsequent orders.
<fix event log>
------------------------------------------------------------------
20131120-03:25:57.913: Disconnecting: Socket exception (/14.129.28.13:35416): java.io.IOException: An existing connection was forcibly closed by the remote host
20131120-03:25:57.991: Already disconnected: Socket exception (/14.129.28.13:35416): java.io.IOException: An existing connection was forcibly closed by the remote host
20131120-03:31:16.621: Accepting session FIX.4.2:CT->S_MHK from /14.129.28.13:36040
<fix message log>
------------------------------------------------------------------
20131120-03:31:16.621: 8=FIX.4.29=011435=A49=S_MHK56=CT34=2397450=desk143=N97=N122=20131120-03:31:1752=20131120-03:31:17108=6698=010=205
20131120-03:31:16.839: 8=FIX.4.29=76 35=A34=5219849=CT52=20131120-03:31:16.83956=S_MHK98=0108=6610=000
20131120-03:31:16.839: 8=FIX.4.29=77 35=234=5219949=CT52=20131120-03:31:16.83956=S_MHK7=2315216=010=024
20131120-03:31:16.917: 8=FIX.4.29=010235=049=S_MHK56=CT34=2397550=desk143=N97=N122=20131120-03:31:1752=20131120-03:31:1710=152
20131120-03:31:16.917: 8=FIX.4.29=011535=249=S_MHK56=CT34=2397650=desk143=N97=N122=20131120-03:31:1752=20131120-03:31:177=5212916=010=236
20131120-03:31:16.980: 8=FIX.4.29=025235=D49=S_MHK56=CT34=2315250=desk143=Y97=Y122=20131120-03:25:4052=20131120-03:31:1711=00000000000276593764-00000115=CNY38=190040=244=000000003.3500000047=P54=155=60166859=060=20131120-03:25:40100=SS526=MHK0127658108421=110=070
20131120-03:31:16.980: 8=FIX.4.29=025235=D49=S_MHK56=CT34=2315350=desk143=Y97=Y122=20131120-03:25:4052=20131120-03:31:1711=00000000000276593765-00000115=CNY38=250040=244=000000004.9800000047=P54=155=60002859=060=20131120-03:25:40100=SS526=MHK0127658105921=110=070
.............................
.............................
.............................
.............................
.............................
20131120-03:31:17.541: 8=FIX.4.29=025135=D49=S_MHK56=CT34=2397150=desk143=Y97=Y122=20131120-03:29:5452=20131120-03:31:1811=00000000000276597970-00000115=CNY38=20040=244=000000005.3000000047=P54=155=60189859=060=20131120-03:29:54100=SS526=MHK0127658108921=110=051
20131120-03:31:17.541: 8=FIX.4.29=025135=D49=S_MHK56=CT34=2397250=desk143=Y97=Y122=20131120-03:29:5452=20131120-03:31:1811=00000000000276597971-00000115=CNY38=10040=244=000000004.2100000047=P54=155=60001959=060=20131120-03:29:54100=SS526=MHK0127658105821=110=031
20131120-03:31:17.541: 8=FIX.4.29=025235=D49=S_MHK56=CT34=2397350=desk143=Y97=Y122=20131120-03:29:5752=20131120-03:31:1811=00000000000276597995-00000115=CNY38=270040=244=000000004.2700000047=P54=155=60180059=060=20131120-03:29:57100=SS526=MHK0127658108521=110=106
20131120-03:31:17.541: 8=FIX.4.29=010735=449=S_MHK56=CT34=23974 43=Y97=Y122=20131120-03:31:1852=20131120-03:31:1836=23977123=Y10=111
20131120-03:31:17.541: 8=FIX.4.29=025335=D49=S_MHK56=CT34=2397750=desk243=N97=N122=20131120-03:31:1852=20131120-03:31:1811=00000000000276599095-00000115=CNY38=70040=244=000000005.7000000047=P54=255=60037759=060=20131120-03:31:17100=SS526=MHK0127647435721=110=238
20131120-03:31:17.541: 8=FIX.4.29=011135=149=S_MHK56=CT34=2397850=desk243=N97=N122=20131120-03:31:1852=20131120-03:31:18112=R110=203
20131120-03:31:17.541: 8=FIX.4.29=7735=234=5224649=CT52=20131120-03:31:17.54156=S_MHK7=2397616=010=022
<fix event log>
------------------------------------------------------------------
20131120-03:31:17.339: Received SequenceReset FROM: 23466 TO: 23467
20131120-03:31:17.417: Received SequenceReset FROM: 23665 TO: 23666
20131120-03:31:17.479: Received SequenceReset FROM: 23887 TO: 23889
20131120-03:31:17.541: ResendRequest for messages FROM 23152 TO 23973 has been satisfied.
20131120-03:31:17.541: Processing queued message: 23974
20131120-03:31:17.541: Processing queued message: 23975
20131120-03:31:17.541: MsgSeqNum too high, expecting 23976 but received 23977: 8=FIX.4.29=25335=D34=2397743=N49=S_MHK50=kerridhu52=20131120-03:31:1856=CT97=N122=20131120-03:31:1811=00000000000276599095-00000115=CNY21=138=70040=244=000000005.7000000047=P54=255=60037759=060=20131120-03:31:17100=SS526=MHK0127647435710=190
20131120-03:31:17.541: Enqueued at pos 23977: 8=FIX.4.29=25335=D34=2397743=N49=S_MHK50=desk252=20131120-03:31:1856=CT97=N122=20131120-03:31:1811=00000000000276599095-00000115=CNY21=138=70040=244=000000005.7000000047=P54=255=60037759=060=20131120-03:31:17100=SS526=MHK0127647435710=190
20131120-03:31:17.541: Sent ResendRequest FROM: 23976 TO: 23976
20131120-03:31:17.541: MsgSeqNum too high, expecting 23976 but received 23978: 8=FIX.4.29=11135=134=2397843=N49=S_MHK50=kerridhu52=20131120-03:31:1856=CT97=N122=20131120-03:31:18112=R110=155
20131120-03:31:17.541: Enqueued at pos 23978: 8=FIX.4.29=11135=134=2397843=N49=S_MHK50=desk252=20131120-03:31:1856=CT97=N122=20131120-03:31:18112=R110=155
20131120-03:31:17.541: Already sent ResendRequest FROM: 23976 TO: 23976. Not sending another.
Attachments
Issue Links
- duplicates
-
QFJ-673 QFJ sequence problem following inbound SequenceReset - a previously received ResendRequest not "queued"?
- Closed