Second logon fails after heartbeat timeout.
|
|
p'ross
|
This sequence of events does not make sense to me.
The first logon is succesfull. The snapshot request works. I get 2 AQ messages back as expected. Then for some reason QF sends a TestRequest out. Next I get timeout waiting on heartbeat. (Iguess ICE never sends it?) The session disconnects and a new session starts. A logon request is sent. Now its in a loop of disconnects and reconnects with logons being ignored? 09:33:30,961 [SocketConnectorIoProcessor-0.0] [] INFO - MINA session created: /10.10.8.120:4266 <20091019-14:33:31, FIX.4.4:454/1->, outgoing> (8=FIX.4.49=9635=A34=149=45450=152=20091019-14:33:31.75856=98=0108=30141=Y553=XXXX554=XXXX10=197) <20091019-14:33:31, FIX.4.4:454/1->, event> (Initiated logon request) <20091019-14:33:32, FIX.4.4:454/1->, incoming> (8=FIX.4.49=7235=A49=ICE34=152=20091019-14:33:31.87356=45457=198=0108=30141=Y10=124) <20091019-14:33:32, FIX.4.4:454/1->, event> (Logon contains ResetSeqNumFlag=Y, resetting sequence numbers to 1) <20091019-14:33:32, FIX.4.4:454/1->, event> (Received logon request) 09:33:32,196 [QFJ Message Processor] [] INFO - Message received for SenderCompID=454 09:33:32,196 [QFJ Message Processor] [] INFO - Session logged on, ID=FIX.4.4:454/1-> SenderCompID=454 09:33:32,258 [QFJ Message Processor] [] INFO - ICEClient::getSnapshot sending request start date=20091018 end date=null <20091019-14:33:32, FIX.4.4:454/1->, outgoing> (8=FIX.4.49=11735=AD34=249=45450=152=20091019-14:33:32.25856=57=263=1568=1569=0580=175=2009101860=20091018-00:00:00.00010=172) <20091019-14:33:32, FIX.4.4:454/1->, incoming> (8=FIX.4.49=7935=AQ49=ICE34=252=20091019-14:33:32.01456=45457=1568=1569=0749=0750=010=195) <20091019-14:33:33, FIX.4.4:454/1->, incoming> (8=FIX.4.49=7935=AQ49=ICE34=352=20091019-14:33:32.78956=45457=1568=1569=0749=0750=110=216) <20091019-14:34:17, FIX.4.4:454/1->, outgoing> (8=FIX.4.49=6035=134=449=45450=152=20091019-14:34:17.72756=112=TEST10=099) <20091019-14:34:17, FIX.4.4:454/1->, event> (Sent test request TEST) <20091019-14:34:44, FIX.4.4:454/1->, event> (Timed out waiting for heartbeat) <20091019-14:34:44, FIX.4.4:454/1->, event> (Disconnecting) 09:34:44,727 [QFJ Timer] [] INFO - Session logged out, ID=FIX.4.4:454/1-> SenderCompID=454 09:34:45,634 [SocketConnectorIoProcessor-0.0] [] INFO - MINA session created: /10.10.8.120:4273 <20091019-14:34:45, FIX.4.4:454/1->, outgoing> (8=FIX.4.49=9635=A34=149=45450=152=20091019-14:34:45.74356=98=0108=30141=Y553=XXXX554=XXXX10=197) <20091019-14:34:45, FIX.4.4:454/1->, event> (Initiated logon request) <20091019-14:34:46, FIX.4.4:454/1->, event> (Disconnecting) 09:34:46,134 [SocketConnectorIoProcessor-0.0] [] INFO - Session logged out, ID=FIX.4.4:454/1-> SenderCompID=454 09:35:15,915 [SocketConnectorIoProcessor-0.0] [] INFO - MINA session created: /10.10.8.120:4276 <20091019-14:35:16, FIX.4.4:454/1->, outgoing> (8=FIX.4.49=9635=A34=149=45450=152=20091019-14:35:16.74356=98=0108=30141=Y553=XXXX554=XXXXX10=196) <20091019-14:35:16, FIX.4.4:454/1->, event> (Initiated logon request) <20091019-14:35:16, FIX.4.4:454/1->, incoming> (8=FIX.4.49=7235=A49=ICE34=152=20091019-14:35:16.52856=45457=198=0108=30141=Y10=126) <20091019-14:35:46, FIX.4.4:454/1->, event> (Timed out waiting for logon response) <20091019-14:35:46, FIX.4.4:454/1->, event> (Disconnecting) 09:35:46,743 [QFJ Timer] [] INFO - Session logged out, ID=FIX.4.4:454/1-> SenderCompID=454 |
||||||||||||||||
|
p'ross
|
I found that this situation was caused by my synchronous thread waiting for the asynchronous QF thread to notify it. So I'm not sure how to get fix data to a synchronous caller, but at least I've got the communication running smoothly again.
|
||||||||||||||||
| Free Embeddable Forum Powered by Nabble | Help |
