Answered by:
Using Audiocodes media gateway

Question
-
I am having trouble calling the speech server from the gateway. The windows enevet viewer says there was a protocol violation:
The Telephony Application Proxy declined a call with Call Id '127212022221200004241@192.168.1.128' from '' for application 'WelcomeToOcs' at URL 'http://localhost/WelcomeToOcs/WelcomeToOcs.speax' when it encountered the following exception:
System.Net.WebException: The server committed a protocol violation. Section=ResponseStatusLine
at System.Net.HttpWebRequest.EndGetResponse(IAsyncResult asyncResult)
at Microsoft.SpeechServer.Tap.Proxy.<>c__DisplayClass7.<HttpWebResponseCallback>b__6()
For more information, see Help and Support Center at
The Log from the gatewyas is :
Log is Activated 1d:23h:54m:43s ( lgr_psbrdex)(2513 ) recv <-- ANALOG_IF_RING_START Ch:0 [File: Line:-1]
1d:23h:54m:43s ( lgr_flow)(2514 ) #0:RING_START_EV [File: Line:-1]
1d:23h:54m:43s ( lgr_flow)(2515 ) | #0:RING_START_EV [File: Line:-1]
1d:23h:54m:45s ( lgr_psbrdex)(2516 ) recv <-- EV_ANALOG_IF_RING_END Ch:0 [File: Line:-1]
1d:23h:54m:45s ( lgr_flow)(2517 ) #0:RING_END_EV [File: Line:-1]
1d:23h:54m:45s ( lgr_flow)(2518 ) | #0:RING_END_EV [File: Line:-1]
1d:23h:54m:49s ( lgr_psbrdex)(2519 ) recv <-- ANALOG_IF_RING_START Ch:0 [File: Line:-1]
1d:23h:54m:49s ( lgr_flow)(2520 ) #0:RING_START_EV [File: Line:-1]
1d:23h:54m:49s ( lgr_flow)(2521 ) | #0:RING_START_EV [File: Line:-1]
1d:23h:54m:51s ( lgr_psbrdex)(2522 ) recv <-- EV_ANALOG_IF_RING_END Ch:0 [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2523 ) #0:RING_END_EV [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2524 ) | #0:RING_END_EV [File: Line:-1]
1d:23h:54m:51s ( lgr_psbrdif)(2525 ) #0:cpDigitMapHndlr_Stop - Stoped (0) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2526 ) | #0:NEW_CALL_EV (send) : (UnKnown) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2527 ) | | #14:NEW_CALL_EVUnKnown) [File: Line:-1]
1d:23h:54m:51s ( lgr_stk_mngr)(2528 ) Resource StackSession <#14> Allocated [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2529 ) | | | #14:NEW_CALL_EV(UNKnown) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2530 ) | (to 100) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2531 ) | #0ETUP (send) : (UnKnown) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2532 ) | | #14ETUP (TO:100, FROM:201)
UnKnown) [File: Line:-1]
1d:23h:54m:51s ( lgr_num)(2533 ) PhoneNumber::RemovePrefix - Number change from 201 to [File: Line:-1]
1d:23h:54m:51s ( lgr_num)(2534 ) PhoneNumber::AddPrefix - Number change from to 0 [File: Line:-1]
1d:23h:54m:51s ( lgr_stk_ses)(2535 ) SIPStackSession::GetPrefixSearchFormat, ProxyWorking = 0, ReplaceReason = 0, SENDINVITE2PROXY = 0 [File: Line:-1]
1d:23h:54m:51s ( lgr_profiling)(2536 ) <Call 14> Profiled<Tel=0,Ip=0>: TelCoderGrId=0 IpCoderGrId=0 JBMinDel=70 JBOptF=7 EEarlyM=1 FaxTM=0 IPDS=46 IsFaxU=1 PI2IP=-1 SigIPDF=46 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 RTPRD=0 SCE=0 VxxTT=2 DTMFVol=20 ECE=1 ECurDis=0 EDigDel=0 ERevP=0 FHPer=400 InG=32 MWIA=0 MWID=0 VVol=33 [File: Line:-1]
1d:23h:54m:51s ( lgr_stk_ses)(2537 ) FindIpDestination: rmRc:0 (OK) IpconnHndl:-1 DstPrefix:100 DstIp:-1062731393 [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2538 ) | | | #14ETUP(UNKnown) [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2539 ) new AcSIPCallAPI created - #14 [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2540 ) new GetNewSIPCall created - #10 [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2541 ) | |(SIPTU#10)SETUP_REQ State:Idle() [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2542 ) SIPCall(#10) changes state from Idle to Inviting [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2543 ) new TcpTransportObject created - #7 [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2544 ) ---- Outgoing SIP Message to 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:54m:51s INVITE sip:100@192.168.1.127 SIP/2.0
Via: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac539446191;alias
Max-Forwards: 70
From: "*" <sip:0@audiocodes.com>;tag=1c539440639
To: <sip:100@192.168.1.127>
Call-ID: 539440206112000235451@192.168.1.128
CSeq: 1 INVITE
Contact: <sip:0@192.168.1.128;transport=tcp>
Supported: em,100rel,timer,replaces,path
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
User-Agent: Audiocodes-Sip-Gateway-MP-118 FXO/v.4.80A.025.004
Content-Type: application/sdp
Content-Length: 233v=0
o=AudiocodesGW 539431437 539431320 IN IP4 192.168.1.128
s=Phone-Call
c=IN IP4 192.168.1.128
t=0 0
m=audio 6000 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
[File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2546 ) TcpTransportObject#7::HandleConnectSuccess - Changing socket state to SIP_TCP_SOCKET_CONNECTED [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2547 ) ---- Incoming SIP Message from 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2548 ) SIP/2.0 100 Trying
FROM: "*"<sip:0@audiocodes.com>;tag=1c539440639
TO: <sip:100@192.168.1.127>
CSEQ: 1 INVITE
CALL-ID: 539440206112000235451@192.168.1.128
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac539446191;alias
CONTENT-LENGTH: 0[File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2549 ) | |(SIPTU#10)100 State:Inviting(539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2550 ) SIPCall(#10) changes state from Inviting to Proceeding [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2551 ) ---- Incoming SIP Message from 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2552 ) SIP/2.0 486 Call declined due to exception.
FROM: "*"<sip:0@audiocodes.com>;tag=1c539440639
TO: <sip:100@192.168.1.127>;tag=efb25f8c1d
CSEQ: 1 INVITE
CALL-ID: 539440206112000235451@192.168.1.128
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac539446191;alias
CONTENT-LENGTH: 0
SERVER: RTCC/3.0.0.0[File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2553 ) | |(SIPTU#10)486 Stateroceeding(539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2554 ) ---- Outgoing SIP Message to 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:54m:51s ACK sip:100@192.168.1.127 SIP/2.0
Via: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac539446191;alias
Max-Forwards: 70
From: "*" <sip:0@audiocodes.com>;tag=1c539440639
To: <sip:100@192.168.1.127>;tag=efb25f8c1d
Call-ID: 539440206112000235451@192.168.1.128
CSeq: 1 ACK
Contact: <sip:0@192.168.1.128;transport=tcp>
Supported: em,timer,replaces,path
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
User-Agent: Audiocodes-Sip-Gateway-MP-118 FXO/v.4.80A.025.004
Content-Length: 0[File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2556 ) SIPCall(#10) changes state from Proceeding to Disconnected [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2557 ) | | | #14IP_TRYING_EV(539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_stk_ses)(2558 ) <SESSION #14> SendToCall - event: PROCEEDING m_Call = 32095376 [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2559 ) | | #14ROCEEDING
539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2560 ) | #0ROCEEDING : (539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2561 ) | | | #14IP_DISCONNECT_EV(539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_stk_ses)(2562 ) <SESSION #14> SendToCall - event: RELEASE m_Call = 32095376 [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2563 ) | | #14:RELEASE539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2564 ) | | #14:RELEASE_ACK539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2565 ) | #0:RELEASE GWAPP_USER_BUSY : (539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_psbrdif)(2566 ) #0:Configure Detectors (Detection=0) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2567 ) | #0:RELEASE_ACK (send) : (539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2568 ) | | #14:RELEASE_ACK539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2569 ) | | | #14:RELEASE_ACK(539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2570 ) | |(SIPTU#10)DISCONNECT_RESPONSE Stateisconnected(539440206112000235451@192.168.1.128) [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2571 ) AcSIPStackAPI::FreeCallAPI - #14 [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2572 ) Setting ApplicationCall of AcSIPCall 31410736 to NULL [File: Line:-1]
1d:23h:54m:51s ( lgr_stk_mngr)(2573 ) Resource StackSession <#14> Deleted [File: Line:-1]
1d:23h:54m:51s ( lgr_psbrdex)(2574 ) InsertBoardEvent- event 102 inserted channel 0 [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2575 ) #0:RELEASE_BECAUSE_IP_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2576 ) | #0:RELEASE_BECAUSE_IP_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:54m:51s ( lgr_psbrdif)(2577 ) Turn ringer OFF for channel 0 [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2578 ) | #0:FXO Release Line [File: Line:-1]
1d:23h:54m:51s ( lgr_psbrdif)(2579 ) #0SOSBoardInterface:
topPlayTone- Called [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2580 ) SIPCallDisconnectedState::TransactionListIsEmpty - Freeing SIPCall#10 [File: Line:-1]
1d:23h:54m:51s ( lgr_flow)(2581 ) | | TransactionUserMngr::ReturnSIPCall - #10 [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2582 ) SIPCall(#10) changes state from Disconnected to Idle [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2583 ) TcpTransportObject#7::CloseConnection - Closing connection for socket 88 [File: Line:-1]
1d:23h:54m:51s ( sip_stack)(2584 ) SIPTCPMngr::ReturnTCPTransportObj - #7 [File: Line:-1]
1d:23h:54m:52s ( lgr_psbrdex)(2585 ) InsertBoardEvent- event 113 inserted channel 0 [File: Line:-1]
1d:23h:54m:52s ( lgr_flow)(2586 ) #0:GUARD_TIME_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:54m:52s ( lgr_flow)(2587 ) | #0:GUARD_TIME_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:54m:55s ( lgr_psbrdex)(2588 ) recv <-- ANALOG_IF_RING_START Ch:0 [File: Line:-1]
1d:23h:54m:55s ( lgr_flow)(2589 ) #0:RING_START_EV [File: Line:-1]
1d:23h:54m:55s ( lgr_flow)(2590 ) | #0:RING_START_EV [File: Line:-1]
1d:23h:54m:57s ( lgr_psbrdex)(2591 ) recv <-- EV_ANALOG_IF_RING_END Ch:0 [File: Line:-1]
1d:23h:54m:57s ( lgr_flow)(2592 ) #0:RING_END_EV [File: Line:-1]
1d:23h:54m:57s ( lgr_flow)(2593 ) | #0:RING_END_EV [File: Line:-1]
1d:23h:55m:1s ( lgr_psbrdex)(2594 ) recv <-- ANALOG_IF_RING_START Ch:0 [File: Line:-1]
1d:23h:55m:1s ( lgr_flow)(2595 ) #0:RING_START_EV [File: Line:-1]
1d:23h:55m:1s ( lgr_flow)(2596 ) | #0:RING_START_EV [File: Line:-1]
1d:23h:55m:3s ( lgr_psbrdex)(2597 ) recv <-- EV_ANALOG_IF_RING_END Ch:0 [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2598 ) #0:RING_END_EV [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2599 ) | #0:RING_END_EV [File: Line:-1]
1d:23h:55m:3s ( lgr_psbrdif)(2600 ) #0:cpDigitMapHndlr_Stop - Stoped (0) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2601 ) | #0:NEW_CALL_EV (send) : (UnKnown) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2602 ) | | #13:NEW_CALL_EVUnKnown) [File: Line:-1]
1d:23h:55m:3s ( lgr_stk_mngr)(2603 ) Resource StackSession <#13> Allocated [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2604 ) | | | #13:NEW_CALL_EV(UNKnown) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2605 ) | (to 100) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2606 ) | #0ETUP (send) : (UnKnown) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2607 ) | | #13ETUP (TO:100, FROM:201)
UnKnown) [File: Line:-1]
1d:23h:55m:3s ( lgr_num)(2608 ) PhoneNumber::RemovePrefix - Number change from 201 to [File: Line:-1]
1d:23h:55m:3s ( lgr_num)(2609 ) PhoneNumber::AddPrefix - Number change from to 0 [File: Line:-1]
1d:23h:55m:3s ( lgr_stk_ses)(2610 ) SIPStackSession::GetPrefixSearchFormat, ProxyWorking = 0, ReplaceReason = 0, SENDINVITE2PROXY = 0 [File: Line:-1]
1d:23h:55m:3s ( lgr_profiling)(2611 ) <Call 13> Profiled<Tel=0,Ip=0>: TelCoderGrId=0 IpCoderGrId=0 JBMinDel=70 JBOptF=7 EEarlyM=1 FaxTM=0 IPDS=46 IsFaxU=1 PI2IP=-1 SigIPDF=46 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 RTPRD=0 SCE=0 VxxTT=2 DTMFVol=20 ECE=1 ECurDis=0 EDigDel=0 ERevP=0 FHPer=400 InG=32 MWIA=0 MWID=0 VVol=33 [File: Line:-1]
1d:23h:55m:3s ( lgr_stk_ses)(2612 ) FindIpDestination: rmRc:0 (OK) IpconnHndl:-1 DstPrefix:100 DstIp:-1062731393 [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2613 ) | | | #13ETUP(UNKnown) [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2614 ) new AcSIPCallAPI created - #13 [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2615 ) new GetNewSIPCall created - #9 [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2616 ) | |(SIPTU#9)SETUP_REQ State:Idle() [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2617 ) SIPCall(#9) changes state from Idle to Inviting [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2618 ) new TcpTransportObject created - #6 [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2619 ) ---- Outgoing SIP Message to 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:55m:3s INVITE sip:100@192.168.1.127 SIP/2.0
Via: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac569448281;alias
Max-Forwards: 70
From: "*" <sip:0@audiocodes.com>;tag=1c569442730
To: <sip:100@192.168.1.127>
Call-ID: 56944229611200023553@192.168.1.128
CSeq: 1 INVITE
Contact: <sip:0@192.168.1.128;transport=tcp>
Supported: em,100rel,timer,replaces,path
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
User-Agent: Audiocodes-Sip-Gateway-MP-118 FXO/v.4.80A.025.004
Content-Type: application/sdp
Content-Length: 233v=0
o=AudiocodesGW 569430750 569430635 IN IP4 192.168.1.128
s=Phone-Call
c=IN IP4 192.168.1.128
t=0 0
m=audio 6000 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
[File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2621 ) TcpTransportObject#6::HandleConnectSuccess - Changing socket state to SIP_TCP_SOCKET_CONNECTED [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2622 ) ---- Incoming SIP Message from 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2623 ) SIP/2.0 100 Trying
FROM: "*"<sip:0@audiocodes.com>;tag=1c569442730
TO: <sip:100@192.168.1.127>
CSEQ: 1 INVITE
CALL-ID: 56944229611200023553@192.168.1.128
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac569448281;alias
CONTENT-LENGTH: 0[File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2624 ) | |(SIPTU#9)100 State:Inviting(56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2625 ) SIPCall(#9) changes state from Inviting to Proceeding [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2626 ) ---- Incoming SIP Message from 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2627 ) SIP/2.0 486 Call declined due to exception.
FROM: "*"<sip:0@audiocodes.com>;tag=1c569442730
TO: <sip:100@192.168.1.127>;tag=8f69157f8
CSEQ: 1 INVITE
CALL-ID: 56944229611200023553@192.168.1.128
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac569448281;alias
CONTENT-LENGTH: 0
SERVER: RTCC/3.0.0.0[File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2628 ) | |(SIPTU#9)486 Stateroceeding(56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2629 ) ---- Outgoing SIP Message to 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:55m:3s ACK sip:100@192.168.1.127 SIP/2.0
Via: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac569448281;alias
Max-Forwards: 70
From: "*" <sip:0@audiocodes.com>;tag=1c569442730
To: <sip:100@192.168.1.127>;tag=8f69157f8
Call-ID: 56944229611200023553@192.168.1.128
CSeq: 1 ACK
Contact: <sip:0@192.168.1.128;transport=tcp>
Supported: em,timer,replaces,path
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
User-Agent: Audiocodes-Sip-Gateway-MP-118 FXO/v.4.80A.025.004
Content-Length: 0[File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2631 ) SIPCall(#9) changes state from Proceeding to Disconnected [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2632 ) | | | #13IP_TRYING_EV(56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_stk_ses)(2633 ) <SESSION #13> SendToCall - event: PROCEEDING m_Call = 32093464 [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2634 ) | | #13ROCEEDING
56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2635 ) | #0ROCEEDING : (56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2636 ) | | | #13IP_DISCONNECT_EV(56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_stk_ses)(2637 ) <SESSION #13> SendToCall - event: RELEASE m_Call = 32093464 [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2638 ) | | #13:RELEASE56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2639 ) | | #13:RELEASE_ACK56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2640 ) | #0:RELEASE GWAPP_USER_BUSY : (56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_psbrdif)(2641 ) #0:Configure Detectors (Detection=0) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2642 ) | #0:RELEASE_ACK (send) : (56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2643 ) | | #13:RELEASE_ACK56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2644 ) | | | #13:RELEASE_ACK(56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2645 ) | |(SIPTU#9)DISCONNECT_RESPONSE Stateisconnected(56944229611200023553@192.168.1.128) [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2646 ) AcSIPStackAPI::FreeCallAPI - #13 [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2647 ) Setting ApplicationCall of AcSIPCall 31403256 to NULL [File: Line:-1]
1d:23h:55m:3s ( lgr_stk_mngr)(2648 ) Resource StackSession <#13> Deleted [File: Line:-1]
1d:23h:55m:3s ( lgr_psbrdex)(2649 ) InsertBoardEvent- event 102 inserted channel 0 [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2650 ) #0:RELEASE_BECAUSE_IP_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2651 ) | #0:RELEASE_BECAUSE_IP_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:55m:3s ( lgr_psbrdif)(2652 ) Turn ringer OFF for channel 0 [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2653 ) | #0:FXO Release Line [File: Line:-1]
1d:23h:55m:3s ( lgr_psbrdif)(2654 ) #0SOSBoardInterface:
topPlayTone- Called [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2655 ) SIPCallDisconnectedState::TransactionListIsEmpty - Freeing SIPCall#9 [File: Line:-1]
1d:23h:55m:3s ( lgr_flow)(2656 ) | | TransactionUserMngr::ReturnSIPCall - #9 [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2657 ) SIPCall(#9) changes state from Disconnected to Idle [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2658 ) TcpTransportObject#6::CloseConnection - Closing connection for socket 89 [File: Line:-1]
1d:23h:55m:3s ( sip_stack)(2659 ) SIPTCPMngr::ReturnTCPTransportObj - #6 [File: Line:-1]
1d:23h:55m:4s ( lgr_psbrdex)(2660 ) InsertBoardEvent- event 113 inserted channel 0 [File: Line:-1]
1d:23h:55m:4s ( lgr_flow)(2661 ) #0:GUARD_TIME_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:55m:4s ( lgr_flow)(2662 ) | #0:GUARD_TIME_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:55m:7s ( lgr_psbrdex)(2663 ) recv <-- ANALOG_IF_RING_START Ch:0 [File: Line:-1]
1d:23h:55m:7s ( lgr_flow)(2664 ) #0:RING_START_EV [File: Line:-1]
1d:23h:55m:7s ( lgr_flow)(2665 ) | #0:RING_START_EV [File: Line:-1]
1d:23h:55m:9s ( lgr_psbrdex)(2666 ) recv <-- EV_ANALOG_IF_RING_END Ch:0 [File: Line:-1]
1d:23h:55m:9s ( lgr_flow)(2667 ) #0:RING_END_EV [File: Line:-1]
1d:23h:55m:9s ( lgr_flow)(2668 ) | #0:RING_END_EV [File: Line:-1]
1d:23h:55m:13s ( lgr_psbrdex)(2669 ) recv <-- ANALOG_IF_RING_START Ch:0 [File: Line:-1]
1d:23h:55m:13s ( lgr_flow)(2670 ) #0:RING_START_EV [File: Line:-1]
1d:23h:55m:13s ( lgr_flow)(2671 ) | #0:RING_START_EV [File: Line:-1]
1d:23h:55m:15s ( lgr_psbrdex)(2672 ) recv <-- EV_ANALOG_IF_RING_END Ch:0 [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2673 ) #0:RING_END_EV [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2674 ) | #0:RING_END_EV [File: Line:-1]
1d:23h:55m:15s ( lgr_psbrdif)(2675 ) #0:cpDigitMapHndlr_Stop - Stoped (0) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2676 ) | #0:NEW_CALL_EV (send) : (UnKnown) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2677 ) | | #12:NEW_CALL_EVUnKnown) [File: Line:-1]
1d:23h:55m:15s ( lgr_stk_mngr)(2678 ) Resource StackSession <#12> Allocated [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2679 ) | | | #12:NEW_CALL_EV(UNKnown) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2680 ) | (to 100) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2681 ) | #0ETUP (send) : (UnKnown) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2682 ) | | #12ETUP (TO:100, FROM:201)
UnKnown) [File: Line:-1]
1d:23h:55m:15s ( lgr_num)(2683 ) PhoneNumber::RemovePrefix - Number change from 201 to [File: Line:-1]
1d:23h:55m:15s ( lgr_num)(2684 ) PhoneNumber::AddPrefix - Number change from to 0 [File: Line:-1]
1d:23h:55m:15s ( lgr_stk_ses)(2685 ) SIPStackSession::GetPrefixSearchFormat, ProxyWorking = 0, ReplaceReason = 0, SENDINVITE2PROXY = 0 [File: Line:-1]
1d:23h:55m:15s ( lgr_profiling)(2686 ) <Call 12> Profiled<Tel=0,Ip=0>: TelCoderGrId=0 IpCoderGrId=0 JBMinDel=70 JBOptF=7 EEarlyM=1 FaxTM=0 IPDS=46 IsFaxU=1 PI2IP=-1 SigIPDF=46 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 RTPRD=0 SCE=0 VxxTT=2 DTMFVol=20 ECE=1 ECurDis=0 EDigDel=0 ERevP=0 FHPer=400 InG=32 MWIA=0 MWID=0 VVol=33 [File: Line:-1]
1d:23h:55m:15s ( lgr_stk_ses)(2687 ) FindIpDestination: rmRc:0 (OK) IpconnHndl:-1 DstPrefix:100 DstIp:-1062731393 [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2688 ) | | | #12ETUP(UNKnown) [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2689 ) new AcSIPCallAPI created - #12 [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2690 ) new GetNewSIPCall created - #8 [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2691 ) | |(SIPTU#8)SETUP_REQ State:Idle() [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2692 ) SIPCall(#8) changes state from Idle to Inviting [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2693 ) new TcpTransportObject created - #5 [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2694 ) ---- Outgoing SIP Message to 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:55m:15s INVITE sip:100@192.168.1.127 SIP/2.0
Via: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac599450081;alias
Max-Forwards: 70
From: "*" <sip:0@audiocodes.com>;tag=1c599440040
To: <sip:100@192.168.1.127>
Call-ID: 599439604112000235515@192.168.1.128
CSeq: 1 INVITE
Contact: <sip:0@192.168.1.128;transport=tcp>
Supported: em,100rel,timer,replaces,path
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
User-Agent: Audiocodes-Sip-Gateway-MP-118 FXO/v.4.80A.025.004
Content-Type: application/sdp
Content-Length: 233v=0
o=AudiocodesGW 599430873 599430757 IN IP4 192.168.1.128
s=Phone-Call
c=IN IP4 192.168.1.128
t=0 0
m=audio 6000 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
[File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2696 ) TcpTransportObject#5::HandleConnectSuccess - Changing socket state to SIP_TCP_SOCKET_CONNECTED [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2697 ) ---- Incoming SIP Message from 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2698 ) SIP/2.0 100 Trying
FROM: "*"<sip:0@audiocodes.com>;tag=1c599440040
TO: <sip:100@192.168.1.127>
CSEQ: 1 INVITE
CALL-ID: 599439604112000235515@192.168.1.128
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac599450081;alias
CONTENT-LENGTH: 0[File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2699 ) | |(SIPTU#8)100 State:Inviting(599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2700 ) SIPCall(#8) changes state from Inviting to Proceeding [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2701 ) ---- Incoming SIP Message from 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2702 ) SIP/2.0 486 Call declined due to exception.
FROM: "*"<sip:0@audiocodes.com>;tag=1c599440040
TO: <sip:100@192.168.1.127>;tag=ffc253287d
CSEQ: 1 INVITE
CALL-ID: 599439604112000235515@192.168.1.128
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac599450081;alias
CONTENT-LENGTH: 0
SERVER: RTCC/3.0.0.0[File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2703 ) | |(SIPTU#8)486 Stateroceeding(599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2704 ) ---- Outgoing SIP Message to 192.168.1.127:5060 ---- [File: Line:-1]
1d:23h:55m:15s ACK sip:100@192.168.1.127 SIP/2.0
Via: SIP/2.0/TCP 192.168.1.128;branch=z9hG4bKac599450081;alias
Max-Forwards: 70
From: "*" <sip:0@audiocodes.com>;tag=1c599440040
To: <sip:100@192.168.1.127>;tag=ffc253287d
Call-ID: 599439604112000235515@192.168.1.128
CSeq: 1 ACK
Contact: <sip:0@192.168.1.128;transport=tcp>
Supported: em,timer,replaces,path
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
User-Agent: Audiocodes-Sip-Gateway-MP-118 FXO/v.4.80A.025.004
Content-Length: 0[File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2706 ) SIPCall(#8) changes state from Proceeding to Disconnected [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2707 ) | | | #12IP_TRYING_EV(599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_stk_ses)(2708 ) <SESSION #12> SendToCall - event: PROCEEDING m_Call = 32091552 [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2709 ) | | #12ROCEEDING
599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2710 ) | #0ROCEEDING : (599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2711 ) | | | #12IP_DISCONNECT_EV(599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_stk_ses)(2712 ) <SESSION #12> SendToCall - event: RELEASE m_Call = 32091552 [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2713 ) | | #12:RELEASE599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2714 ) | | #12:RELEASE_ACK599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2715 ) | #0:RELEASE GWAPP_USER_BUSY : (599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_psbrdif)(2716 ) #0:Configure Detectors (Detection=0) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2717 ) | #0:RELEASE_ACK (send) : (599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2718 ) | | #12:RELEASE_ACK599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2719 ) | | | #12:RELEASE_ACK(599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2720 ) | |(SIPTU#8)DISCONNECT_RESPONSE Stateisconnected(599439604112000235515@192.168.1.128) [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2721 ) AcSIPStackAPI::FreeCallAPI - #12 [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2722 ) Setting ApplicationCall of AcSIPCall 31395776 to NULL [File: Line:-1]
1d:23h:55m:15s ( lgr_stk_mngr)(2723 ) Resource StackSession <#12> Deleted [File: Line:-1]
1d:23h:55m:15s ( lgr_psbrdex)(2724 ) InsertBoardEvent- event 102 inserted channel 0 [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2725 ) #0:RELEASE_BECAUSE_IP_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2726 ) | #0:RELEASE_BECAUSE_IP_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:55m:15s ( lgr_psbrdif)(2727 ) Turn ringer OFF for channel 0 [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2728 ) | #0:FXO Release Line [File: Line:-1]
1d:23h:55m:15s ( lgr_psbrdif)(2729 ) #0SOSBoardInterface:
topPlayTone- Called [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2730 ) SIPCallDisconnectedState::TransactionListIsEmpty - Freeing SIPCall#8 [File: Line:-1]
1d:23h:55m:15s ( lgr_flow)(2731 ) | | TransactionUserMngr::ReturnSIPCall - #8 [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2732 ) SIPCall(#8) changes state from Disconnected to Idle [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2733 ) TcpTransportObject#5::CloseConnection - Closing connection for socket 90 [File: Line:-1]
1d:23h:55m:15s ( sip_stack)(2734 ) SIPTCPMngr::ReturnTCPTransportObj - #5 [File: Line:-1]
1d:23h:55m:16s ( lgr_psbrdex)(2735 ) InsertBoardEvent- event 113 inserted channel 0 [File: Line:-1]
1d:23h:55m:16s ( lgr_flow)(2736 ) #0:GUARD_TIME_TIMER_EXPIRED_EV [File: Line:-1]
1d:23h:55m:16s ( lgr_flow)(2737 ) | #0:GUARD_TIME_TIMER_EXPIRED_EV [File: Line:-1]Wednesday, May 23, 2007 9:47 PM
Answers
-
The issue is resolved, but really don't know how. I just reinstalled IIS and it started working.Thursday, June 7, 2007 11:38 PM
All replies
-
When a SIP INVITE arrives for MSS on port 5060, an HTTP request is made to the w3wp.exe process hosting the application requested (in this case, WelcomeToOcs). Normally, w3wp.exe responds with the port on which it's listening for SIP connections, and that information gets relayed back to the GW in the SIP 302. What seems to have happened here is that the HTTP response was fundamentally malformed somehow - so much so that we didn't even get the chance to try to parse the information we expected out of the response.
I'm not sure what would cause that, but I doubt it has anything to do with the GW which initiated the call - the HTTP conversation is entirely internal, and things seem to have gone wrong at the level of the web server or HttpWebRequest class. The only thing I can tell you to try is reinstalling IIS - maybe something in your configuration is borked? I realize that's a lame answer.
If you can get logs of the HTTP traffic, I might be able to tell you more. Unfortunately, our logging doesn't record that in detail, so you'd need to use a network monitoring tool or similar.
Thursday, May 31, 2007 8:30 PM -
Perhaps the problem is similar to this persons:
http://www.codeattest.com/blogs/martin/2006/06/server-committed-protocol-violation.html
Thursday, May 31, 2007 8:48 PM -
Hi,
Can you let us know the status of your issue?If you have solved it, can you share your fix with the forums? If not, did you check out the post suggested by Anthony?
Thanks.
Thursday, June 7, 2007 6:51 PM -
The issue is resolved, but really don't know how. I just reinstalled IIS and it started working.Thursday, June 7, 2007 11:38 PM