Inbound calls to MP114 are dropped: "[ERROR] SIPStackSession::HandleStackSetupEV failed because no remote IP"

Doc ID    SOLN260880
Version:    2.0
Status:    Published
Published date:    13 Mar 2017
Created Date:    15 Dec 2014
Author:   
Levente Szabo
 

Details

Session Manager: 6.3.9
MP114: 6.20
CM: 6.3

AAEP 7 and ICR 7

Problem Clarification

Unable to recive calls on MP114 since upgrade from SES to Session Mgr 6.3.9
Inbound calls to MP114 are dropped: "[ERROR] SIPStackSession::HandleStackSetupEV failed because no remote IP"
Outbound calls from MP114 from same stations are OK.
 

Cause

Misconfiguration

Below snippet is form the  MP114 trace after it got the INVITE:

3d:17h:35m:39s (     sip_stack)(350700    )  new AcSIPCallAPI created - #5 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350701    )  |       | new GetNewSIPCall created - #8 [Time: 17:35:39]
3d:17h:35m:39s (  lgr_stk_mngr)(350702    )  Resource StackSession <#5> Allocated [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350703    )  TcpTransportObject#10::CheckForConnectionPersistent - Opening persistent connection with proxy: 10.72.224.31:40450 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350704    )  |       |(SIPTU#8)INVITE State:Idle() [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350705    )  SIPCall(#8) changes state from Idle to Invited [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350706    )  InitialParsedSDPParams::New - id = 0 [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350707    )  InitialParsedSDPMedia::New - id = 2 [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350708    )  InitialParsedSDPMedia::Delete - id = 2 [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350709    )  InitialParsedSDPParams::Delete - id = 0 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350710    )  |       |       |       #5:SIP_SETUP_EV(0bebd454a90e418b3754786f2000) [Time: 17:35:39]
3d:17h:35m:39s (      lgr_call)(350711    )  (#5) Call Allocated. [Time: 17:35:39]
3d:17h:35m:39s (   lgr_stk_ses)(350712    )  SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 [Time: 17:35:39]
3d:17h:35m:39s (   lgr_stk_ses)(350713    )  <SESSION #5> SendToCall - event: NEW_CALL_EV  m_Call#5 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350714    )  |       |       #5:NEW_CALL_EV:(0bebd454a90e418b3754786f2000) [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350715    )  |       |       #5:Call changing states from:IdleState to:NewCallState_IP2Tel [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350716    )  ServicesMngr::GetEndPoint PhoneNum = 3129 [Time: 17:35:39]
3d:17h:35m:39s (   lgr_psbrdif)(350717    )  GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:3129 DstPfx:3129 SrcNum:2228 SrcPfx:* SrcIp:a48e00a SrcIpPfx:* [Time: 17:35:39]
3d:17h:35m:39s (      lgr_call)(350718    )  Call::SetCoderListForCall #5 Found 3 Common Coders For Call [Time: 17:35:39]
3d:17h:35m:39s (      lgr_call)(350719    )  <Call #5> Coder g711Alaw64k20 : 20 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_call)(350720    )  <Call #5> Coder g72920 : 20 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_call)(350721    )  <Call #5> Coder g711Ulaw64k20 : 20 [Time: 17:35:39]
3d:17h:35m:39s ( lgr_profiling)(350722    )  <Call 5> Profiled<Tel=1,Ip=1>: JBMinDel=10 JBOptF=10 EEarlyM=0 FaxTM=0 IPDS=46 IsFaxU=3 PI2IP=-1 SigIPDF=40 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=1 RBUdpPort=0 RTPRD=0 SCE=0 VxxTT=2 Dst2Rdrt=0 DTMFVol=20 ECE=1 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 InG=32 MWIA=0 MWID=0 VVol=32 ReorderTime=255 DIDWink=0 2StageDial=1 DiscOnBusyT=1 DiscOnBrok=0 DPInd=-1 AGC=0 NLP=0 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_call)(350723    )  |       |       #5GetNextUI:GlobalUI=353790886, mACAddrLsb=3284101 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_call)(350724    )  |       |       #5GetNextUI:GlobalUI=353790887 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350725    )  |       #0:NEW_CALL_EV   : (0bebd454a90e418b3754786f2000) [Time: 17:35:39]
3d:17h:35m:39s (  lgr_endpoint)(350726    )  #0: Endpoint SM: from: IDLE / sub_None    to: IDLE / sub_New_Call [Time: 17:35:39]
3d:17h:35m:39s (  lgr_endpoint)(350727    )  <0> EndPoint::ActivatePeer active peers curent active:0 [Time: 17:35:39]
3d:17h:35m:39s (  lgr_endpoint)(350728    )  #0: EndPoint GoActive [Time: 17:35:39]
3d:17h:35m:39s (  lgr_endpoint)(350729    )  #0:Digit Map Support Enabled [Time: 17:35:39]
3d:17h:35m:39s (   lgr_stk_ses)(350730    ) !! [ERROR] SIPStackSession::HandleStackSetupEV failed because no remote IP [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350731    )  New SIPMessage created - #34 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350732    )  |       |(SIPTU#8)DISCONNECT_REQ State:Invited(0bebd454a90e418b3754786f2000) [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350733    ) ?? [WARNING] SIPCallInvitedState::Disconnect Incorrect release reason, set reason to 487 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350734    )  ---- Outgoing SIP Message to 10.72.224.31:5060 from SIPInterface #0 ---- [Time: 17:35:39]

3d:17h:35m:39s SIP/2.0 487 Request Terminated
Via: SIP/2.0/TCP 10.72.224.31;branch=z9hG4bK597886064700463-AP;ft=12579
Via: SIP/2.0/TCP 10.72.224.30:15060;rport=38121;ibmsid=local.1415655144131_1058676_1058769;branch=z9hG4bK597886064700463
Via: SIP/2.0/TCP 10.72.224.30:15060;rport;ibmsid=local.1415655144131_1058675_1058768;branch=z9hG4bK240525013054083
Via: SIP/2.0/TLS 10.72.224.31;branch=z9hG4bK0bebd454a90e418c3754786f2000-AP;ft=1671345;received=10.72.224.31;rport=37151
Via: SIP/2.0/TLS 10.72.224.10;branch=z9hG4bK0bebd454a90e418c3754786f2000
Via: SIP/2.0/TCP 10.72.95.15;branch=z9hG4bK0bebd454a90e418c3754786f2000
From: "Wittchen, Sebastian" <sip:[email protected]>;tag=0bebd454a90e418a3754786f2000
To: <sip:[email protected]>;tag=1c1577522631
Call-ID: 0bebd454a90e418b3754786f2000
CSeq: 1 INVITE
Record-Route: <sip:[email protected];transport=tcp;lr>,<sip:10.72.224.30:15060;transport=tcp;ibmsi

3d:17h:35m:39s r: Audiocodes-Sip-Gateway-MP-114 FXS/v.6.20A.066.005
Content-Length: 0

 [Time: 17:35:39]

3d:17h:35m:39s (     sip_stack)(350736    )  SIPCall(#8) changes state from Invited to Disconnected [Time: 17:35:39]
3d:17h:35m:39s (   lgr_stk_ses)(350737    )  <SESSION #5> SendToCall - event: RELEASE_EV  m_Call#5 [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350738    )  |       |       #5:RELEASE_EV:(0bebd454a90e418b3754786f2000) [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350739    )  |       |       #5:Call changing states from:NewCallState_IP2Tel to:DisconnectingState [Time: 17:35:39]
3d:17h:35m:39s |CALL_END    |0    |0              |-1   |0    |0    |1    |FXS       |RMT  |10.72.224.10        |                    |0    |0    |2228                |2228                |0    |0    |3129                |3129                |0    |N/A            |255  |                    |0    |RMT  |GWAPP_NO_ROUTE_TO_DESTINATION           |0    |0         |0         |0         |-1            |0bebd454a90e418b3754786f2000                      |                                   |                                   |17:35:39.000  UTC     Dec 03 2014  |0        |0        |1234527438|-1            |-1             |0    |0    |                    |0                   |                    |                    |                    |                    |1    |                    |0    |         |      [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350741    )  |       |       #5:RELEASE_ACK_EV:(0bebd454a90e418b3754786f2000) [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350742    )  |       #0:RELEASE_EV  GWAPP_NO_ROUTE_TO_DESTINATION : (0bebd454a90e418b3754786f2000) [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350743    )  |       #0:RELEASE_ACK_EV (send)  : (0bebd454a90e418b3754786f2000) [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350744    )  |       |       #5:RELEASE_ACK_EV:(0bebd454a90e418b3754786f2000) [Time: 17:35:39]
3d:17h:35m:39s (      lgr_flow)(350745    )  |       |       |       #5:RELEASE_ACK_EV(0bebd454a90e418b3754786f2000) [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350746    )  New SIPMessage created - #32 [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350747    )  AcSIPStackAPI::FreeCallAPI - #5 [Time: 17:35:39]
3d:17h:35m:39s (     sip_stack)(350748    )  Setting ApplicationCall of AcSIPCall 32056824 to NULL [Time: 17:35:39]
 

Solution

Make sure to strictly follow the integration document:

Application Notes for AudioCodes MediaPack 11x with Avaya Aura® Communication Manager and Avaya Aura Session Manager
– Issue 1.0:
http://www.devconnectprogram.com/fileMedia/download/fcd19bc6-8f22-4da3-ba57-3d7d84972e75
 

In this particluar case to get this work the following changes were done:

MP114:
- changed back the endpoint phone number tel profile from 1 to the global 0. FAX and voice calls can use the global profile, separate profile needed for modem only.
- changed the ip to hunt group routing: ip profile id back to 0 and source ipgroup id to -1, also added * to dest host prefix and source host prefix
 
SMGR:
- edit user 3129 (analog station behind the MP114 gateway):
                - SM profile: i added the application sequence that points to CM as origination and termination application
                - CM endpoint profile: checked it as it was unchecked. As extension 3129 was already administered in CM i have ticked use existing extension and typed/selected 3129.
 
After the above changes the test call from x2228 to x3129 was successful.


Avaya -- Proprietary. Use pursuant to the terms of your signed agreement or Avaya policy