locked
Using Audiocodes media gateway RRS feed

  • 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_EVSadUnKnown) [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      )  |       #0Tongue TiedETUP (send)  : (UnKnown) [File: Line:-1]
    1d:23h:54m:51s (      lgr_flow)(2532      )  |       |       #14Tongue TiedETUP (TO:100, FROM:201)SadUnKnown) [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      )  |       |       |       #14Tongue TiedETUP(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: 233

    v=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 StateStick out tongueroceeding(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      )  |       |       |       #14Tongue TiedIP_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      )  |       |       #14Stick out tongueROCEEDINGSad539440206112000235451@192.168.1.128) [File: Line:-1]
    1d:23h:54m:51s (      lgr_flow)(2560      )  |       #0Stick out tongueROCEEDING   : (539440206112000235451@192.168.1.128) [File: Line:-1]
    1d:23h:54m:51s (      lgr_flow)(2561      )  |       |       |       #14Tongue TiedIP_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:RELEASESad539440206112000235451@192.168.1.128) [File: Line:-1]
    1d:23h:54m:51s (      lgr_flow)(2564      )  |       |       #14:RELEASE_ACKSad539440206112000235451@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_ACKSad539440206112000235451@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 StateBig Smileisconnected(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      )  #0Stick out tongueSOSBoardInterface:Tongue TiedtopPlayTone- 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_EVSadUnKnown) [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      )  |       #0Tongue TiedETUP (send)  : (UnKnown) [File: Line:-1]
    1d:23h:55m:3s (      lgr_flow)(2607      )  |       |       #13Tongue TiedETUP (TO:100, FROM:201)SadUnKnown) [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      )  |       |       |       #13Tongue TiedETUP(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: 233

    v=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 StateStick out tongueroceeding(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      )  |       |       |       #13Tongue TiedIP_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      )  |       |       #13Stick out tongueROCEEDINGSad56944229611200023553@192.168.1.128) [File: Line:-1]
    1d:23h:55m:3s (      lgr_flow)(2635      )  |       #0Stick out tongueROCEEDING   : (56944229611200023553@192.168.1.128) [File: Line:-1]
    1d:23h:55m:3s (      lgr_flow)(2636      )  |       |       |       #13Tongue TiedIP_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:RELEASESad56944229611200023553@192.168.1.128) [File: Line:-1]
    1d:23h:55m:3s (      lgr_flow)(2639      )  |       |       #13:RELEASE_ACKSad56944229611200023553@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_ACKSad56944229611200023553@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 StateBig Smileisconnected(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      )  #0Stick out tongueSOSBoardInterface:Tongue TiedtopPlayTone- 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_EVSadUnKnown) [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      )  |       #0Tongue TiedETUP (send)  : (UnKnown) [File: Line:-1]
    1d:23h:55m:15s (      lgr_flow)(2682      )  |       |       #12Tongue TiedETUP (TO:100, FROM:201)SadUnKnown) [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      )  |       |       |       #12Tongue TiedETUP(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: 233

    v=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 StateStick out tongueroceeding(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      )  |       |       |       #12Tongue TiedIP_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      )  |       |       #12Stick out tongueROCEEDINGSad599439604112000235515@192.168.1.128) [File: Line:-1]
    1d:23h:55m:15s (      lgr_flow)(2710      )  |       #0Stick out tongueROCEEDING   : (599439604112000235515@192.168.1.128) [File: Line:-1]
    1d:23h:55m:15s (      lgr_flow)(2711      )  |       |       |       #12Tongue TiedIP_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:RELEASESad599439604112000235515@192.168.1.128) [File: Line:-1]
    1d:23h:55m:15s (      lgr_flow)(2714      )  |       |       #12:RELEASE_ACKSad599439604112000235515@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_ACKSad599439604112000235515@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 StateBig Smileisconnected(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      )  #0Stick out tongueSOSBoardInterface:Tongue TiedtopPlayTone- 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
  • 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