Bounty for a quick Solicit-Response Sample

Topics: Developer Forum
Oct 24, 2008 at 1:46 AM
Edited Oct 24, 2008 at 10:53 AM
I'm having a problem getting the request- response sample working as laid out in the documentation.  I can get a request to from the client throught the port to the server, but the server is never able to return the message back to the client.  In short, the solicit response port is not returning data back to the biztalk. I need this functionality for a project i have coming up and I've exhausted my know how.  Therefore I'd like to pay someone to help me get this functionality working.  It looks like im over looking something small, but i dont know what it could be.  Thanks.My log file is posted below. It represents a connect and send from the client app. The data is being received by the server app, but the return data is not being displayed client app. 

update: Please look at the last two entries of the log. Also notice the time between the the end of beginning of call and the end. 

10/24/2008 5:41:37 AM.656: 43: The socket accept callback has been invoked.
10/24/2008 5:41:37 AM.656: 43: Accepted new socket on port 1971 from client 127.0.0.1:1443.
10/24/2008 5:41:37 AM.671: 43: The dispatcher has received a new work item of type BatchWorkItem to dispatch to a worker thread.
10/24/2008 5:41:37 AM.671: 14: A work item of type BatchWorkItem has been dispatched to worker thread TcpRecvBatchWorkerThread44.
10/24/2008 5:41:37 AM.671: 14: The worker thread TcpRecvBatchWorkerThread53 is starting.
10/24/2008 5:41:37 AM.671: 53: The worker thread TcpRecvBatchWorkerThread53 is waiting for work...
10/24/2008 5:41:37 AM.671: 14: The worker thread TcpRecvBatchWorkerThread53 has started.
10/24/2008 5:41:37 AM.671: 14: The dispatcher is waiting for work...
10/24/2008 5:41:37 AM.671: 53: There is work to be done on thread TcpRecvBatchWorkerThread53, getting BizTalk TransportProxy batch to submit messages.
10/24/2008 5:41:37 AM.671: 53: Dequeued work for receive location TCP://computername:1971 on thread TcpRecvBatchWorkerThread53.
10/24/2008 5:41:37 AM.671: 53: About to receive data on socket using the following parameters: frame start = 5B 53 5D, frame end = 5B 45 5D, encoding = System.Text.ASCIIEncoding, data buffer size = 2048, accept multiple messages = False.
10/24/2008 5:41:38 AM.406: 53: 10 bytes read from the socket.
10/24/2008 5:41:38 AM.406: 53: State >>> Start Frame Not Found
10/24/2008 5:41:38 AM.406: 53: Start frame 5B 53 5D found in data.
10/24/2008 5:41:38 AM.406: 53: State >>> Start Frame Found [Create Message]
10/24/2008 5:41:38 AM.406: 53: State >>> Start Frame Found
10/24/2008 5:41:38 AM.406: 53: End frame 5B 45 5D found in data.
10/24/2008 5:41:38 AM.406: 53: A message has been received on the socket - exiting loop early to avoid receive timeout.
10/24/2008 5:41:38 AM.406: 53: No more data received from the socket, finished.
10/24/2008 5:41:38 AM.406: 53: Submitting request message of a request-response pair for receive location TCP://computername:1971 to batch.
10/24/2008 5:41:38 AM.406: 53: Timeout of 250 milliseconds reached on batch - submitting batch of 1 messages to BizTalk.
10/24/2008 5:41:38 AM.406: 53: The worker thread TcpRecvBatchWorkerThread53 is waiting for work...
10/24/2008 5:41:38 AM.562: 54: The batch 7bc159ae-fb27-46bd-aa10-a26ef194007d was accepted by BizTalk.
10/24/2008 5:41:38 AM.781: 47: BizTalk has called BeginBatch() - returning maximum transmit batch size of 10.
10/24/2008 5:41:38 AM.812: 47: BizTalk has passed a message to the adapter for transmission, message id = 6d59452f-172a-41c1-8a79-f990f61c0d34.
10/24/2008 5:41:38 AM.812: 47: Loading adapter configuration...
10/24/2008 5:41:38 AM.812: 47: The send location is configured as static.
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Endpoint Address = computername
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Endpoint Port = 1970
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Socket Close Timeout (secs) = 0
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Socket Receive Buffer Size (bytes) = 8192
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Socket Receive Timeout (ms) = 0
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Socket Send Buffer Size (bytes) = 8192
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Socket Send Timeout (ms) = 0
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Supports Duplex = False
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Receive Data Buffer Size (bytes) = 2048
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Send Data Buffer Size (bytes) = 2048
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Data: Output Byte Order Mark = False
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Data: Byte Order = LittleEndian
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Data: Custom Encoding Value =
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Data: Encoding = System.Text.ASCIIEncoding
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Frame: Output Byte Order Mark = False
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Frame: Byte Order = LittleEndian
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Frame: Custom Encoding Value =
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Frame: Encoding = System.Text.ASCIIEncoding
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Frame Start = [start]
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Frame End = [end]
10/24/2008 5:41:38 AM.812: 47: Send Location TCP://computername:1970: Two Way Send Port = True
10/24/2008 5:41:38 AM.812: 47: BizTalk has called Done() on the batch, passing off 1 messages to the dispatcher to be processed.
10/24/2008 5:41:38 AM.812: 47: The dispatcher has received a new work item of type TransmitWorkItem to dispatch to a worker thread.
10/24/2008 5:41:38 AM.812: 48: A work item of type TransmitWorkItem has been dispatched to worker thread TcpSendTransmitWorkerThread49.
10/24/2008 5:41:38 AM.812: 48: The worker thread TcpSendTransmitWorkerThread55 is starting.
10/24/2008 5:41:38 AM.812: 55: The worker thread TcpSendTransmitWorkerThread55 is waiting for work...
10/24/2008 5:41:38 AM.812: 48: The worker thread TcpSendTransmitWorkerThread55 has started.
10/24/2008 5:41:38 AM.812: 48: The dispatcher is waiting for work...
10/24/2008 5:41:38 AM.812: 55: Dequeued transmit work for send location TCP://computername:1970 on thread TcpSendTransmitWorkerThread55.
10/24/2008 5:41:38 AM.812: 55: Sending data to remote endpoint computername:1970 in a solicit-response pattern on thread TcpSendTransmitWorkerThread55.
10/24/2008 5:41:38 AM.812: 55: About to send data on socket using the following parameters: frame start = 5B 73 74 61 72 74 5D, frame end = 5B 65 6E 64 5D, encoding = System.Text.ASCIIEncoding, data buffer size = 2048, number of messages = 1.
10/24/2008 5:41:38 AM.812: 55: Sending message number 1 of size 4 on socket.
10/24/2008 5:41:38 AM.812: 55: Converting data from encoding System.Text.UTF8Encoding to encoding System.Text.ASCIIEncoding.
10/24/2008 5:41:38 AM.812: 55: 7 bytes sent to the socket.
10/24/2008 5:41:38 AM.812: 55: 4 bytes sent to the socket.
10/24/2008 5:41:38 AM.812: 55: 5 bytes sent to the socket.
10/24/2008 5:41:38 AM.812: 55: Sent message 1 to the client.
10/24/2008 5:41:38 AM.812: 55: About to receive data on socket using the following parameters: frame start = 5B 73 74 61 72 74 5D, frame end = 5B 65 6E 64 5D, encoding = System.Text.ASCIIEncoding, data buffer size = 2048, accept multiple messages = False.
10/24/2008 5:41:38 AM.812: 55: 33 bytes read from the socket.
10/24/2008 5:41:38 AM.812: 55: State >>> Start Frame Not Found
10/24/2008 5:41:38 AM.812: 55: Start frame 5B 73 74 61 72 74 5D found in data.
10/24/2008 5:41:38 AM.812: 55: State >>> Start Frame Found [Create Message]
10/24/2008 5:41:38 AM.812: 55: State >>> Start Frame Found
10/24/2008 5:41:38 AM.812: 55: End frame 5B 65 6E 64 5D found in data.
10/24/2008 5:41:38 AM.812: 55: A message has been received on the socket - exiting loop early to avoid receive timeout.
10/24/2008 5:41:38 AM.812: 55: No more data received from the socket, finished.
10/24/2008 5:41:38 AM.828: 55: Received response for send location TCP://computername:1970 on thread TcpSendTransmitWorkerThread55 - submitting response to BizTalk and deleting solicit message.
10/24/2008 5:41:38 AM.828: 55: The dispatcher has received a new work item of type BatchWorkItem to dispatch to a worker thread.
10/24/2008 5:41:38 AM.828: 48: A work item of type BatchWorkItem has been dispatched to worker thread TcpSendBatchWorkerThread50.
10/24/2008 5:41:38 AM.828: 48: The worker thread TcpSendBatchWorkerThread56 is starting.
10/24/2008 5:41:38 AM.828: 55: The worker thread TcpSendTransmitWorkerThread55 is waiting for work...
10/24/2008 5:41:38 AM.828: 48: The worker thread TcpSendBatchWorkerThread56 has started.
10/24/2008 5:41:38 AM.828: 56: The worker thread TcpSendBatchWorkerThread56 is waiting for work...
10/24/2008 5:41:38 AM.828: 56: There is work to be done on thread TcpSendBatchWorkerThread56, getting BizTalk TransportProxy batch to submit messages.
10/24/2008 5:41:38 AM.828: 56: Dequeued work for send location TCP://computername:1970 on thread TcpSendBatchWorkerThread56.
10/24/2008 5:41:38 AM.828: 56: The thread TcpSendBatchWorkerThread56 is adding a response message to the batch for send location TCP://computername:1970.
10/24/2008 5:41:38 AM.828: 48: The dispatcher is waiting for work...
10/24/2008 5:41:38 AM.828: 56: The thread TcpSendBatchWorkerThread56 is adding a delete operation to the batch for send location TCP://computername:1970.
10/24/2008 5:41:38 AM.828: 56: Submitting batch of 2 messages to BizTalk.
10/24/2008 5:41:38 AM.828: 56: The worker thread TcpSendBatchWorkerThread56 is waiting for work...
BTSNTSvc.exe Error: 0 : 10/24/2008 5:41:38 AM.843: 54: The batch callback indicated an error occurred with batch 8b55c26a-fb3c-4e02-9a23-0a2c622d5d45, the COM error status is -1061153243
BTSNTSvc.exe Warning: 0 : 10/24/2008 5:41:38 AM.843: 54: The batch operation Submit was successful however the overall batch failed - resubmitting message.
BTSNTSvc.exe Warning: 0 : 10/24/2008 5:41:38 AM.843: 54: The batch operation Delete failed with HRESULT -2146232798.  Moving the message to the suspend queue.
BTSNTSvc.exe Error: 0 : 10/24/2008 5:41:38 AM.843: 57: The batch callback indicated an error occurred with batch e46dae1f-1365-43df-9321-5a69bf1eb32c, the COM error status is -1061153235
BTSNTSvc.exe Warning: 0 : 10/24/2008 5:41:38 AM.843: 57: The batch operation Submit was successful however the overall batch failed - resubmitting message.
BTSNTSvc.exe Warning: 0 : 10/24/2008 5:41:38 AM.843: 57: The batch operation MoveToSuspendQ failed with HRESULT -2146232798.  Moving the message to the suspend queue.
BTSNTSvc.exe Error: 0 : 10/24/2008 5:41:38 AM.843: 54: The batch callback indicated an error occurred with batch b147c981-dc12-465b-b555-29274534a9fa, the COM error status is -1061153235
BTSNTSvc.exe Warning: 0 : 10/24/2008 5:41:38 AM.843: 54: The batch operation Submit was successful however the overall batch failed - resubmitting message.
BTSNTSvc.exe Warning: 0 : 10/24/2008 5:41:38 AM.843: 54: The batch operation MoveToSuspendQ failed with HRESULT -2146232798.  Moving the message to the suspend queue.
BTSNTSvc.exe Error: 0 : 10/24/2008 5:41:38 AM.843: 57: The batch callback indicated an error occurred with batch fddb655c-6fa2-44bc-b1b5-e1883c3982d2, the COM error status is -1061153235
BTSNTSvc.exe Warning: 0 : 10/24/2008 5:41:38 AM.843: 57: The batch operation Submit was successful however the overall batch failed - resubmitting message.
BTSNTSvc.exe Warning: 0 : 10/24/2008 5:41:38 AM.843: 57: The batch operation MoveToSuspendQ failed with HRESULT -2146232798.  Moving the message to the suspend queue.
BTSNTSvc.exe Error: 0 : 10/24/2008 5:41:38 AM.843: 54: The batch callback indicated an error occurred with batch 3b48c0bd-3537-4f09-9568-166dfb727658, the COM error status is -1061153235
BTSNTSvc.exe Warning: 0 : 10/24/2008 5:41:38 AM.843: 54: The batch operation Submit was successful however the overall batch failed - resubmitting message.
BTSNTSvc.exe Warning: 0 : 10/24/2008 5:41:38 AM.859: 54: The batch operation MoveToSuspendQ failed with HRESULT -2146232798.  Moving the message to the suspend queue.
BTSNTSvc.exe Error: 0 : 10/24/2008 5:41:38 AM.859: 57: The batch callback indicated an error occurred with batch d34f9675-09c6-4508-9883-f8154e3f1af1, the COM error status is -1061153235
BTSNTSvc.exe Error: 0 : 10/24/2008 5:41:38 AM.859: 57: The failure depth (based on batch retry count) of the batch has been exceeded, the adapter cannot process the batch any further.
BTSNTSvc.exe Error: 0 : 10/24/2008 5:41:38 AM.859: 57: The batch d34f9675-09c6-4508-9883-f8154e3f1af1 failed with error 10/24/2008 5:41:38 AM.859: 57: The failure depth (based on batch retry count) of the batch has been exceeded, the adapter cannot process the batch any further. while handling prior batch failure.
10/24/2008 5:41:38 AM.859: 57: Saving data to dead message folder with filename \87fd78e1-645f-44bb-b6e7-7d9ce218a19e.msg.
10/24/2008 5:46:43 AM.250: 15: The dispatcher is shutting down idle thread TcpRecvBatchWorkerThread53.
10/24/2008 5:46:43 AM.250: 15: The worker thread TcpRecvBatchWorkerThread53 is stopping.
10/24/2008 5:46:43 AM.250: 15: The worker thread TcpRecvBatchWorkerThread53 has stopped.
10/24/2008 5:46:43 AM.250: 53: The worker thread TcpRecvBatchWorkerThread53 has been interrupted and will shut down gracefully.
10/24/2008 5:46:43 AM.750: 15: The dispatcher is shutting down idle thread TcpSendBatchWorkerThread56.
10/24/2008 5:46:43 AM.750: 15: The worker thread TcpSendBatchWorkerThread56 is stopping.
10/24/2008 5:46:43 AM.750: 15: The worker thread TcpSendBatchWorkerThread56 has stopped.
10/24/2008 5:46:43 AM.750: 15: The dispatcher is shutting down idle thread TcpSendTransmitWorkerThread55.
10/24/2008 5:46:43 AM.750: 15: The worker thread TcpSendTransmitWorkerThread55 is stopping.
10/24/2008 5:46:43 AM.750: 56: The worker thread TcpSendBatchWorkerThread56 has been interrupted and will shut down gracefully.
10/24/2008 5:46:43 AM.750: 15: The worker thread TcpSendTransmitWorkerThread55 has stopped.
10/24/2008 5:46:43 AM.750: 55: The worker thread TcpSendTransmitWorkerThread55 has been interrupted and will shut down gracefully.
10/24/2008 5:47:37 AM.968: 59: BizTalk has returned a response to the two way receive location TCP://computername:1971.  BizTalk message ID = bd24ed56-6311-47c6-9b31-f1cc228e18f0.
10/24/2008 5:47:37 AM.968: 59: The response returned is a NACK, closing socket without returning any data.