Hello,
I am looking for some help on getting SIP calls from an Avaya IP Office to a AudioCodes Mediant 1000 then out to an ISDN trunk.
I can see the call come into the Mediant 1000, however, they never go out of the gateway....I get the following error...
(My phone display shows INCOMPATIBLE) See traces below....
08:52:10.812 : 10.13.80.190 : Local 0 :NOTICE : [S=18896] [SID:981772393] ( sip_sbc)( 18536) SIPSBCMngr::GetStackEP - No user is found in DB
08:52:10.821 : 10.13.80.190 : Local 0 :NOTICE : [S=18897] [SID:981772393] ( lgr_sbc)( 18537) (#6) SBCRoutesIterator Allocated.
08:52:10.826 : 10.13.80.190 : Local 0 :NOTICE : [S=18898] [SID:981772393] ( lgr_sbc)( 18538) Classification Succeeded - Source IP Group #1.
08:52:10.831 : 10.13.80.190 : Local 0 :NOTICE : [S=18899] [SID:981772393] ( lgr_sbc)( 18539) CallAdmission::AddDialog: Type INVITE Direction In IP group 1: 1 SRD 1: 1 SRD ovflw:1 used unres:1
08:52:10.837 : 10.13.80.190 : Local 0 :NOTICE : [S=18900] [SID:981772393] ( lgr_flow)( 18540) (#6)SBCRoutesIterator::Change State From: InitialRouting To : AlternativeRouting
08:52:10.843 : 10.13.80.190 : Local 0 :NOTICE : [S=18901] [SID:981772393] ( lgr_sbc)( 18541) CallAdmission::AddDialog: Type INVITE Direction Out IP group 3: 1 SRD 1: 1 SRD ovflw:1 used unres:2
08:52:10.849 : 10.13.80.190 : Local 0 :NOTICE : [S=18902] [SID:981772393] ( lgr_flow)( 18542) (#6)SBCRoutesIterator::Next route found: Route by: IPGroup , IP Group ID: 3, Live:True
08:52:10.851 : 10.13.80.190 : Local 0 :NOTICE : [S=18903] [SID:981772393] ( lgr_sbc)( 18543) Routing Succeeded -IP2IPRouting Rule #1.
08:52:10.858 : 10.13.80.190 : Local 0 :NOTICE : [S=18904] [SID:981772393] ( lgr_flow)( 18544) ---- Incoming SIP Message from 10.13.80.51:5060 to SIPInterface #1 UdpTransportObject(#1) ----
08:52:10.862 : 10.13.80.190 : Local 0 :NOTICE : [S=18905] [SID:981772393] INVITE tel:+53306 SIP/2.0
Via: SIP/2.0/UDP 10.13.80.51:5060;rport;branch=z9hG4bKd3a9d94ac91d17be6af171ade94e2472
From: "7011" <tel:+7011>;tag=941b2a0b46e2861f
To: <tel:+53306>
Call-ID: a6b070bfeafb56bf62922b8685bca4bc
CSeq: 1407010355 INVITE
Contact: <tel:+7011>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Content-Type: application/sdp
Supported: timer
User-Agent: IP Office 9.0.1.0 build 845
Content-Length: 296
v=0
o=UserA 881305972 2478460195 IN IP4 10.13.80.51
s=Session SDP
c=IN IP4 10.13.80.51
t=0 0
m=audio 49152 RTP/AVP 4 18 8 0 101
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
08:52:10.873 : 10.13.80.190 : Local 0 :NOTICE : [S=18906] [SID:981772393] ( sip_stack)( 18546) New SIPMessage created - (#159)
08:52:10.876 : 10.13.80.190 : Local 0 :NOTICE : [S=18907] [SID:981772393] ( sip_stack)( 18547) New SIPSBCCallLeg created - (#179)
08:52:10.879 : 10.13.80.190 : Local 0 :NOTICE : [S=18908] [SID:981772393] ( sip_stack)( 18548) New AcSIPCall created - (#562)
08:52:10.882 : 10.13.80.190 : Local 0 :NOTICE : [S=18909] [SID:981772393] ( sip_stack)( 18549) AcTransactionUser::AddMessageToQueue: Queueing message
08:52:10.885 : 10.13.80.190 : Local 0 :NOTICE : [S=18910] [SID:981772393] ( lgr_flow)( 18550) | | | (#179)SIPSBCCallLeg <- (#0): SBCNewCallData
08:52:10.888 : 10.13.80.190 : Local 0 :NOTICE : [S=18911] [SID:981772393] ( lgr_call)( 18551) (#426) CALL Allocated.
08:52:10.891 : 10.13.80.190 : Local 0 :NOTICE : [S=18912] [SID:981772393] ( lgr_stk_ses)( 18552) SESSION (#179) SendToCall - event: NEW_CALL_EV m_Call(#426)
08:52:10.893 : 10.13.80.190 : Local 0 :NOTICE : [S=18913] [SID:981772393] ( lgr_flow)( 18553) | | (#426)SBCCall <- (#179)SIPSBCCallLeg: NEW_CALL_EV
08:52:10.896 : 10.13.80.190 : Local 0 :NOTICE : [S=18914] [SID:981772393] ( lgr_flow)( 18554) | | (#426) SBCCall changing states from:IdleState to:NewCallState
08:52:10.898 : 10.13.80.190 : Local 0 :NOTICE : [S=18915] [SID:981772393] ( lgr_profiling)( 18555) <Call 426> Profiled<Tel=0,Ip=1>: JBMinDel=10 JBOptF=10 JBMaxDel=250 IPDS=46 SigIPDF=40 RTPRD=0 ECE=0 InG=32 VVol=32 DiscOnBrok=0 MIPVP=0
ExtCGrp=1 TMod=0 AllMedia= AllCGrp=1 AllVCGrp=-1 ACdrM=0 MSBeh=1 R2833B=1 AltDM=0 AssrtID=0 DivM=0 HisIM=0 FaxCGId=-1 FaxBeh=0 FaxOffM=0 FaxAnsM=1 PrkMod=3 RmtUpdSup=1
RmtRvtSup=2 RmtDOS=1 RmtRB=3 Rmt3B=2 RmtM18xS=1 RmtEMRT=0 RmtEMS=1 RmtRBSR=1 RmtSR3960=0 RmtCPRB=1 RmtHF=0 SDPPTA=0 PrfPT=0 USS=0 2833PT=0 RmtRepB=0 RTPRedB=0 PlRbTr=0
08:52:10.904 : 10.13.80.190 : Local 0 :NOTICE : [S=18916] [SID:981772393] ( lgr_flow)( 18556) ServicesMngr::GetEndPoint PhoneNum = +53306
08:52:10.906 : 10.13.80.190 : Local 0 :NOTICE : [S=18917] [SID:981772393] ( media_service)( 18557) (#242) CONTROLLER Allocated.
08:52:10.908 : 10.13.80.190 : Local 0 :NOTICE : [S=18918] [SID:981772393] ( media_service)( 18558) ServicesMngr: Allocate SBC session. current active: 0 and max is: 10
08:52:10.911 : 10.13.80.190 : Local 0 :NOTICE : [S=18919] [SID:981772393] ( media_service)( 18559) (#426)ENDPOINT Allocated.
08:52:10.929 : 10.13.80.190 : Local 0 :NOTICE : [S=18920] [SID:981772393] ( media_service)( 18560) (#425)ENDPOINT Allocated.
08:52:10.964 : 10.13.80.190 : Local 0 :NOTICE : [S=18921] [SID:981772393] ( lgr_flow)( 18561) | (#425)SBCParticipantEndPoint <- (#426)SBCCall: NEW_CALL_EV
08:52:10.999 : 10.13.80.190 : Local 0 :NOTICE : [S=18922] [SID:981772393] ( lgr_flow)( 18562) (#425) SBCParticipantEndPoint changing states from:IdleState to:NewCallState
08:52:11.034 : 10.13.80.190 : Local 0 :NOTICE : [S=18923] [SID:981772393] ( lgr_flow)( 18563) (#242)SBCController <- (#425)SBCParticipantEndPoint: NEW_CALL_EV
08:52:11.068 : 10.13.80.190 : Local 0 :NOTICE : [S=18924] [SID:981772393] ( lgr_flow)( 18564) | | (#242) SBCController changing states from:IdleState to:NewCallState
08:52:11.101 : 10.13.80.190 : Local 0 :NOTICE : [S=18925] [SID:981772393] ( lgr_flow)( 18565) | (#426)SBCParticipantEndPoint <- (#242)SBCController: NEW_CALL_EV
08:52:11.135 : 10.13.80.190 : Local 0 :NOTICE : [S=18926] [SID:981772393] ( lgr_flow)( 18566) (#426) SBCParticipantEndPoint changing states from:IdleState to:NewCallState
08:52:11.169 : 10.13.80.190 : Local 0 :NOTICE : [S=18927] [SID:981772393] ( lgr_sbc)( 18567) (#28) FEATURE Allocated.
08:52:11.203 : 10.13.80.190 : Local 0 :NOTICE : [S=18928] [SID:981772393] ( lgr_flow)( 18568) | (#28)SBCCallPlacementFeature <- (#426)SBCParticipantEndPoint: NEW_CALL_EV
08:52:11.238 : 10.13.80.190 : Local 0 :NOTICE : [S=18929] [SID:981772393] ( lgr_call)( 18569) (#425) CALL Allocated.
08:52:11.276 : 10.13.80.190 : Local 0 :NOTICE : [S=18930] [SID:981772393] ( lgr_flow)( 18570) | | (#425)SBCCall <- (#28)SBCCallPlacementFeature: NEW_CALL_EV
08:52:11.312 : 10.13.80.190 : Local 0 :NOTICE : [S=18931] [SID:981772393] ( lgr_flow)( 18571) | | (#425) SBCCall changing states from:IdleState to:NewCallState
08:52:11.351 : 10.13.80.190 : Local 0 :NOTICE : [S=18932] [SID:981772393] ( lgr_profiling)( 18572) <Call 425> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 JBMaxDel=250 IPDS=46 SigIPDF=40 RTPRD=0 ECE=0 InG=0 VVol=0 DiscOnBrok=1 MIPVP=0
ExtCGrp=-1 TMod=0 AllMedia= AllCGrp=-1 AllVCGrp=-1 ACdrM=0 MSBeh=0 R2833B=0 AltDM=0 AssrtID=0 DivM=0 HisIM=0 FaxCGId=-1 FaxBeh=0 FaxOffM=0 FaxAnsM=1 PrkMod=3 RmtUpdSup=2
RmtRvtSup=2 RmtDOS=1 RmtRB=0 Rmt3B=0 RmtM18xS=1 RmtEMRT=0 RmtEMS=1 RmtRBSR=0 RmtSR3960=0 RmtCPRB=1 RmtHF=0 SDPPTA=0 PrfPT=0 USS=0 2833PT=0 RmtRepB=0 RTPRedB=0 PlRbTr=0
08:52:11.393 : 10.13.80.190 : Local 0 :NOTICE : [S=18933] [SID:981772393] ( lgr_stk_mngr)( 18573) (#178)SIPSBCCallLeg Allocated.
08:52:11.434 : 10.13.80.190 : Local 0 :NOTICE : [S=18934] [SID:981772393] ( lgr_flow)( 18574) | | | (#178)SIPSBCCallLeg <- (#425)SBCCall: NEW_CALL_EV
08:52:11.436 : 10.13.80.190 : Local 0 :NOTICE : [S=18935] [SID:981772393] ( sip_stack)( 18575) AcTransactionUser::HandleQueuedMessages: Handle a queued message
08:52:11.479 : 10.13.80.190 : Local 0 :NOTICE : [S=18936] [SID:981772393] ( lgr_flow)( 18576) | |(SIPTU(#562)INVITE State:Idle(a6b070bfeafb56bf62922b8685bca4bc)
08:52:11.524 : 10.13.80.190 : Local 0 :NOTICE : [S=18937] [SID:981772393] ( sip_sbc)( 18577) SIPSBCMngr::GetStackEP - No user is found in DB
08:52:11.569 : 10.13.80.190 : Local 0 :NOTICE : [S=18938] [SID:981772393] ( sip_stack)( 18578) TU(#562) SIPSessionTimer::HandleSTRequest - Session-Timer mode: SUPPORTED
08:52:11.614 : 10.13.80.190 : Local 0 :NOTICE : [S=18939] [SID:981772393] ( sip_stack)( 18579) SIPCall(#562) changes state from Idle to Invited
08:52:11.660 : 10.13.80.190 : Local 0 :NOTICE : [S=18940] [SID:981772393] ( lgr_flow)( 18580) | | | (#179)SIPSBCCallLeg <- (#179)AcSBCCallAPI: SBCSetupData
08:52:11.661 : 10.13.80.190 : Local 0 :NOTICE : [S=18941] [SID:981772393] ( sip_stack)( 18581) SDPBody::New - id = (#139)
08:52:11.718 : 10.13.80.190 : Local 0 :NOTICE : [S=18942] [SID:981772393] ( sip_stack)( 18582) SDPBodyMedia::New - id = (#1023)
08:52:11.719 : 10.13.80.190 : Local 0 :NOTICE : [S=18943] [SID:981772393] ( lgr_flow)( 18583) (#5) MediaMetaDataList Allocated.
08:52:11.772 : 10.13.80.190 : Local 0 :NOTICE : [S=18944] [SID:981772393] ( lgr_stk_ses)( 18584) SBCOfferAnswerMngr(#179) changes state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED
08:52:11.825 : 10.13.80.190 : Local 0 :NOTICE : [S=18945] [SID:981772393] ( media_connect)( 18585) (#142) MediaResourcesConnector Allocated.
08:52:11.826 : 10.13.80.190 : Local 0 :NOTICE : [S=18946] [SID:981772393] ( media_service)( 18586) (#307) MediaResourceAdapter Allocated.
08:52:11.883 : 10.13.80.190 : Local 0 :WARNING : [S=18947] [SID:981772393] ( lgr_stk_ses)( 18587) ?? [WARNING] SBCSDPFeatureMngr::HandleIncomingOffer - All medias are not supported.
08:52:11.937 : 10.13.80.190 : Local 0 :NOTICE : [S=18948] [SID:981772393] ( lgr_stk_ses)( 18588) SBCOfferAnswerMngr#179 - OfferAnswerCompleted not in SIP_MEDIA_COMPLETED state. Returning to previous state: SIP_MEDIA_IDLE
08:52:11.938 : 10.13.80.190 : Local 0 :NOTICE : [S=18949] [SID:981772393] ( sip_stack)( 18589) New SIPMessage created - (#161)
08:52:11.943 : 10.13.80.190 : Local 0 :NOTICE : [S=18950] [SID:981772393] ( lgr_flow)( 18590) | |(SIPTU(#562)DISCONNECT_REQ State:Invited(a6b070bfeafb56bf62922b8685bca4bc)
08:52:11.944 : 10.13.80.190 : Local 0 :NOTICE : [S=18951] [SID:981772393] ( lgr_flow)( 18591) ---- Outgoing SIP Message to 10.13.80.51:5060 from SIPInterface #1 UdpTransportObject(#1) ----
08:52:11.945 : 10.13.80.190 : Local 0 :NOTICE : [S=18952] [SID:981772393] SIP/2.0 488 Not Acceptable Here
Via: SIP/2.0/UDP 10.13.80.51:5060;received=10.13.80.51;rport=5060;branch=z9hG4bKd3a9d94ac91d17be6af171ade94e2472
From: "7011" <tel:+7011>;tag=941b2a0b46e2861f
To: <tel:+53306>;tag=1c1765771694
Call-ID: a6b070bfeafb56bf62922b8685bca4bc
CSeq: 1407010355 INVITE
Server: PRDUCGW1/v.6.80A.231.002
Reason: SIP ;cause=488 ;text="488 Not Acceptable Here"
Content-Length: 0
08:52:11.946 : 10.13.80.190 : Local 0 :NOTICE : [S=18953] [SID:981772393] ( sip_stack)( 18593) UdpRtxMngr::Transmit 488 Response 1407010355 INVITE Rtx Left: 6 Dest: 10.13.80.51:5060 CallID: (a6b070bfeafb56bf62922b8685bca4bc)
08:52:11.947 : 10.13.80.190 : Local 0 :NOTICE : [S=18954] [SID:981772393] ( sip_stack)( 18594) SIPCall(#562) changes state from Invited to Disconnected
08:52:11.948 : 10.13.80.190 : Local 0 :NOTICE : [S=18955] [SID:981772393] ( lgr_stk_ses)( 18595) SESSION (#179) SendToCall - event: RELEASE_EV m_Call(#426)
08:52:11.949 : 10.13.80.190 : Local 0 :NOTICE : [S=18956] [SID:981772393] ( lgr_flow)( 18596) | | (#426)SBCCall <- (#179)SIPSBCCallLeg: RELEASE_EV
08:52:11.950 : 10.13.80.190 : Local 0 :NOTICE : [S=18957] [SID:981772393] ( lgr_flow)( 18597) | | (#426) SBCCall changing states from:NewCallState to
isconnectingState
08:52:11.951 : 10.13.80.190 : Local 0 :NOTICE : [S=18958] [SID:981772393] ( lgr_flow)( 18598) | (#425)SBCParticipantEndPoint <- (#426)SBCCall: RELEASE_EV
08:52:11.952 : 10.13.80.190 : Local 0 :NOTICE : [S=18959] [SID:981772393] ( lgr_flow)( 18599) (#425) SBCParticipantEndPoint changing states from:NewCallState to:ReleaseingState
08:52:11.952 : 10.13.80.190 : Local 0 :NOTICE : [S=18960] [SID:981772393] ( lgr_flow)( 18600) (#242)SBCController <- (#425)SBCParticipantEndPoint: RELEASE_EV
08:52:11.953 : 10.13.80.190 : Local 0 :NOTICE : [S=18961] [SID:981772393] ( lgr_flow)( 18601) | | (#242) SBCController changing states from:NewCallState to
isconnectingState
08:52:11.955 : 10.13.80.190 : Local 0 :NOTICE : [S=18962] [SID:981772393] ( lgr_flow)( 18602) | (#426)SBCParticipantEndPoint <- (#242)SBCController: RELEASE_EV
08:52:11.956 : 10.13.80.190 : Local 0 :NOTICE : [S=18963] [SID:981772393] ( lgr_flow)( 18603) (#426) SBCParticipantEndPoint changing states from:NewCallState to:ReleaseingState
08:52:11.957 : 10.13.80.190 : Local 0 :NOTICE : [S=18964] [SID:981772393] ( lgr_flow)( 18604) | (#28)SBCCallPlacementFeature <- (#426)SBCParticipantEndPoint: RELEASE_EV
08:52:11.958 : 10.13.80.190 : Local 0 :NOTICE : [S=18965] [SID:981772393] ( lgr_flow)( 18605) (#28) SBCCallPlacementFeature changing states from: Idle to: Releasing
08:52:11.958 : 10.13.80.190 : Local 0 :NOTICE : [S=18966] [SID:981772393] ( lgr_flow)( 18606) | | (#425)SBCCall <- (#28)SBCCallPlacementFeature: RELEASE_EV
08:52:11.959 : 10.13.80.190 : Local 0 :NOTICE : [S=18967] [SID:981772393] ( lgr_flow)( 18607) | | (#425) SBCCall changing states from:NewCallState to
isconnectingState
08:52:11.960 : 10.13.80.190 : Local 0 :NOTICE : [S=18968] [SID:981772393] ( lgr_flow)( 18608) | | | (#178)SIPSBCCallLeg <- (#425)SBCCall: RELEASE_EV
08:52:11.961 : 10.13.80.190 : Local 0 :NOTICE : [S=18969] [SID:981772393] ( sip_stack)( 18609) New SIPMessage created - (#163)
08:52:11.961 : 10.13.80.190 : Local 0 :WARNING : [S=18970] [SID:981772393] ( sip_stack)( 18610) !! [ERROR] AcSBCCallAPI:
isconnectCall failed, can't get SIPCall
08:52:11.962 : 10.13.80.190 : Local 0 :NOTICE : [S=18971] [SID:981772393] ( lgr_stk_ses)( 18611) SESSION (#178) SendToCall - event: RELEASE_ACK_EV m_Call(#425)
08:52:11.963 : 10.13.80.190 : Local 0 :NOTICE : [S=18972] [SID:981772393] ( lgr_stk_ses)( 18612) (#178)SIPSBCCallLeg Deallocated
08:52:11.963 : 10.13.80.190 : Local 0 :NOTICE : [S=18973] [SID:981772393] ( sip_stack)( 18613) Resource SIPMessage deleted - (#163)
08:52:11.964 : 10.13.80.190 : Local 0 :NOTICE : [S=18974] [SID:981772393] ( sip_stack)( 18614) Resource SIPMessage deleted - (#161)
08:52:11.965 : 10.13.80.190 : Local 0 :NOTICE : [S=18975] [SID:981772393] ( sip_stack)( 18615) SDPBody:
elete - id = (#139)
08:52:11.966 : 10.13.80.190 : Local 0 :NOTICE : [S=18976] [SID:981772393] ( sip_stack)( 18616) SDPBodyMedia:
elete - id = (#1023)
08:52:11.967 : 10.13.80.190 : Local 0 :NOTICE : [S=18977] [SID:981772393] ( sip_stack)( 18617) Resource SIPMessage deleted - (#159)
I am looking for some help on getting SIP calls from an Avaya IP Office to a AudioCodes Mediant 1000 then out to an ISDN trunk.
I can see the call come into the Mediant 1000, however, they never go out of the gateway....I get the following error...
(My phone display shows INCOMPATIBLE) See traces below....
08:52:10.812 : 10.13.80.190 : Local 0 :NOTICE : [S=18896] [SID:981772393] ( sip_sbc)( 18536) SIPSBCMngr::GetStackEP - No user is found in DB
08:52:10.821 : 10.13.80.190 : Local 0 :NOTICE : [S=18897] [SID:981772393] ( lgr_sbc)( 18537) (#6) SBCRoutesIterator Allocated.
08:52:10.826 : 10.13.80.190 : Local 0 :NOTICE : [S=18898] [SID:981772393] ( lgr_sbc)( 18538) Classification Succeeded - Source IP Group #1.
08:52:10.831 : 10.13.80.190 : Local 0 :NOTICE : [S=18899] [SID:981772393] ( lgr_sbc)( 18539) CallAdmission::AddDialog: Type INVITE Direction In IP group 1: 1 SRD 1: 1 SRD ovflw:1 used unres:1
08:52:10.837 : 10.13.80.190 : Local 0 :NOTICE : [S=18900] [SID:981772393] ( lgr_flow)( 18540) (#6)SBCRoutesIterator::Change State From: InitialRouting To : AlternativeRouting
08:52:10.843 : 10.13.80.190 : Local 0 :NOTICE : [S=18901] [SID:981772393] ( lgr_sbc)( 18541) CallAdmission::AddDialog: Type INVITE Direction Out IP group 3: 1 SRD 1: 1 SRD ovflw:1 used unres:2
08:52:10.849 : 10.13.80.190 : Local 0 :NOTICE : [S=18902] [SID:981772393] ( lgr_flow)( 18542) (#6)SBCRoutesIterator::Next route found: Route by: IPGroup , IP Group ID: 3, Live:True
08:52:10.851 : 10.13.80.190 : Local 0 :NOTICE : [S=18903] [SID:981772393] ( lgr_sbc)( 18543) Routing Succeeded -IP2IPRouting Rule #1.
08:52:10.858 : 10.13.80.190 : Local 0 :NOTICE : [S=18904] [SID:981772393] ( lgr_flow)( 18544) ---- Incoming SIP Message from 10.13.80.51:5060 to SIPInterface #1 UdpTransportObject(#1) ----
08:52:10.862 : 10.13.80.190 : Local 0 :NOTICE : [S=18905] [SID:981772393] INVITE tel:+53306 SIP/2.0
Via: SIP/2.0/UDP 10.13.80.51:5060;rport;branch=z9hG4bKd3a9d94ac91d17be6af171ade94e2472
From: "7011" <tel:+7011>;tag=941b2a0b46e2861f
To: <tel:+53306>
Call-ID: a6b070bfeafb56bf62922b8685bca4bc
CSeq: 1407010355 INVITE
Contact: <tel:+7011>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Content-Type: application/sdp
Supported: timer
User-Agent: IP Office 9.0.1.0 build 845
Content-Length: 296
v=0
o=UserA 881305972 2478460195 IN IP4 10.13.80.51
s=Session SDP
c=IN IP4 10.13.80.51
t=0 0
m=audio 49152 RTP/AVP 4 18 8 0 101
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
08:52:10.873 : 10.13.80.190 : Local 0 :NOTICE : [S=18906] [SID:981772393] ( sip_stack)( 18546) New SIPMessage created - (#159)
08:52:10.876 : 10.13.80.190 : Local 0 :NOTICE : [S=18907] [SID:981772393] ( sip_stack)( 18547) New SIPSBCCallLeg created - (#179)
08:52:10.879 : 10.13.80.190 : Local 0 :NOTICE : [S=18908] [SID:981772393] ( sip_stack)( 18548) New AcSIPCall created - (#562)
08:52:10.882 : 10.13.80.190 : Local 0 :NOTICE : [S=18909] [SID:981772393] ( sip_stack)( 18549) AcTransactionUser::AddMessageToQueue: Queueing message
08:52:10.885 : 10.13.80.190 : Local 0 :NOTICE : [S=18910] [SID:981772393] ( lgr_flow)( 18550) | | | (#179)SIPSBCCallLeg <- (#0): SBCNewCallData
08:52:10.888 : 10.13.80.190 : Local 0 :NOTICE : [S=18911] [SID:981772393] ( lgr_call)( 18551) (#426) CALL Allocated.
08:52:10.891 : 10.13.80.190 : Local 0 :NOTICE : [S=18912] [SID:981772393] ( lgr_stk_ses)( 18552) SESSION (#179) SendToCall - event: NEW_CALL_EV m_Call(#426)
08:52:10.893 : 10.13.80.190 : Local 0 :NOTICE : [S=18913] [SID:981772393] ( lgr_flow)( 18553) | | (#426)SBCCall <- (#179)SIPSBCCallLeg: NEW_CALL_EV
08:52:10.896 : 10.13.80.190 : Local 0 :NOTICE : [S=18914] [SID:981772393] ( lgr_flow)( 18554) | | (#426) SBCCall changing states from:IdleState to:NewCallState
08:52:10.898 : 10.13.80.190 : Local 0 :NOTICE : [S=18915] [SID:981772393] ( lgr_profiling)( 18555) <Call 426> Profiled<Tel=0,Ip=1>: JBMinDel=10 JBOptF=10 JBMaxDel=250 IPDS=46 SigIPDF=40 RTPRD=0 ECE=0 InG=32 VVol=32 DiscOnBrok=0 MIPVP=0
ExtCGrp=1 TMod=0 AllMedia= AllCGrp=1 AllVCGrp=-1 ACdrM=0 MSBeh=1 R2833B=1 AltDM=0 AssrtID=0 DivM=0 HisIM=0 FaxCGId=-1 FaxBeh=0 FaxOffM=0 FaxAnsM=1 PrkMod=3 RmtUpdSup=1
RmtRvtSup=2 RmtDOS=1 RmtRB=3 Rmt3B=2 RmtM18xS=1 RmtEMRT=0 RmtEMS=1 RmtRBSR=1 RmtSR3960=0 RmtCPRB=1 RmtHF=0 SDPPTA=0 PrfPT=0 USS=0 2833PT=0 RmtRepB=0 RTPRedB=0 PlRbTr=0
08:52:10.904 : 10.13.80.190 : Local 0 :NOTICE : [S=18916] [SID:981772393] ( lgr_flow)( 18556) ServicesMngr::GetEndPoint PhoneNum = +53306
08:52:10.906 : 10.13.80.190 : Local 0 :NOTICE : [S=18917] [SID:981772393] ( media_service)( 18557) (#242) CONTROLLER Allocated.
08:52:10.908 : 10.13.80.190 : Local 0 :NOTICE : [S=18918] [SID:981772393] ( media_service)( 18558) ServicesMngr: Allocate SBC session. current active: 0 and max is: 10
08:52:10.911 : 10.13.80.190 : Local 0 :NOTICE : [S=18919] [SID:981772393] ( media_service)( 18559) (#426)ENDPOINT Allocated.
08:52:10.929 : 10.13.80.190 : Local 0 :NOTICE : [S=18920] [SID:981772393] ( media_service)( 18560) (#425)ENDPOINT Allocated.
08:52:10.964 : 10.13.80.190 : Local 0 :NOTICE : [S=18921] [SID:981772393] ( lgr_flow)( 18561) | (#425)SBCParticipantEndPoint <- (#426)SBCCall: NEW_CALL_EV
08:52:10.999 : 10.13.80.190 : Local 0 :NOTICE : [S=18922] [SID:981772393] ( lgr_flow)( 18562) (#425) SBCParticipantEndPoint changing states from:IdleState to:NewCallState
08:52:11.034 : 10.13.80.190 : Local 0 :NOTICE : [S=18923] [SID:981772393] ( lgr_flow)( 18563) (#242)SBCController <- (#425)SBCParticipantEndPoint: NEW_CALL_EV
08:52:11.068 : 10.13.80.190 : Local 0 :NOTICE : [S=18924] [SID:981772393] ( lgr_flow)( 18564) | | (#242) SBCController changing states from:IdleState to:NewCallState
08:52:11.101 : 10.13.80.190 : Local 0 :NOTICE : [S=18925] [SID:981772393] ( lgr_flow)( 18565) | (#426)SBCParticipantEndPoint <- (#242)SBCController: NEW_CALL_EV
08:52:11.135 : 10.13.80.190 : Local 0 :NOTICE : [S=18926] [SID:981772393] ( lgr_flow)( 18566) (#426) SBCParticipantEndPoint changing states from:IdleState to:NewCallState
08:52:11.169 : 10.13.80.190 : Local 0 :NOTICE : [S=18927] [SID:981772393] ( lgr_sbc)( 18567) (#28) FEATURE Allocated.
08:52:11.203 : 10.13.80.190 : Local 0 :NOTICE : [S=18928] [SID:981772393] ( lgr_flow)( 18568) | (#28)SBCCallPlacementFeature <- (#426)SBCParticipantEndPoint: NEW_CALL_EV
08:52:11.238 : 10.13.80.190 : Local 0 :NOTICE : [S=18929] [SID:981772393] ( lgr_call)( 18569) (#425) CALL Allocated.
08:52:11.276 : 10.13.80.190 : Local 0 :NOTICE : [S=18930] [SID:981772393] ( lgr_flow)( 18570) | | (#425)SBCCall <- (#28)SBCCallPlacementFeature: NEW_CALL_EV
08:52:11.312 : 10.13.80.190 : Local 0 :NOTICE : [S=18931] [SID:981772393] ( lgr_flow)( 18571) | | (#425) SBCCall changing states from:IdleState to:NewCallState
08:52:11.351 : 10.13.80.190 : Local 0 :NOTICE : [S=18932] [SID:981772393] ( lgr_profiling)( 18572) <Call 425> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 JBMaxDel=250 IPDS=46 SigIPDF=40 RTPRD=0 ECE=0 InG=0 VVol=0 DiscOnBrok=1 MIPVP=0
ExtCGrp=-1 TMod=0 AllMedia= AllCGrp=-1 AllVCGrp=-1 ACdrM=0 MSBeh=0 R2833B=0 AltDM=0 AssrtID=0 DivM=0 HisIM=0 FaxCGId=-1 FaxBeh=0 FaxOffM=0 FaxAnsM=1 PrkMod=3 RmtUpdSup=2
RmtRvtSup=2 RmtDOS=1 RmtRB=0 Rmt3B=0 RmtM18xS=1 RmtEMRT=0 RmtEMS=1 RmtRBSR=0 RmtSR3960=0 RmtCPRB=1 RmtHF=0 SDPPTA=0 PrfPT=0 USS=0 2833PT=0 RmtRepB=0 RTPRedB=0 PlRbTr=0
08:52:11.393 : 10.13.80.190 : Local 0 :NOTICE : [S=18933] [SID:981772393] ( lgr_stk_mngr)( 18573) (#178)SIPSBCCallLeg Allocated.
08:52:11.434 : 10.13.80.190 : Local 0 :NOTICE : [S=18934] [SID:981772393] ( lgr_flow)( 18574) | | | (#178)SIPSBCCallLeg <- (#425)SBCCall: NEW_CALL_EV
08:52:11.436 : 10.13.80.190 : Local 0 :NOTICE : [S=18935] [SID:981772393] ( sip_stack)( 18575) AcTransactionUser::HandleQueuedMessages: Handle a queued message
08:52:11.479 : 10.13.80.190 : Local 0 :NOTICE : [S=18936] [SID:981772393] ( lgr_flow)( 18576) | |(SIPTU(#562)INVITE State:Idle(a6b070bfeafb56bf62922b8685bca4bc)
08:52:11.524 : 10.13.80.190 : Local 0 :NOTICE : [S=18937] [SID:981772393] ( sip_sbc)( 18577) SIPSBCMngr::GetStackEP - No user is found in DB
08:52:11.569 : 10.13.80.190 : Local 0 :NOTICE : [S=18938] [SID:981772393] ( sip_stack)( 18578) TU(#562) SIPSessionTimer::HandleSTRequest - Session-Timer mode: SUPPORTED
08:52:11.614 : 10.13.80.190 : Local 0 :NOTICE : [S=18939] [SID:981772393] ( sip_stack)( 18579) SIPCall(#562) changes state from Idle to Invited
08:52:11.660 : 10.13.80.190 : Local 0 :NOTICE : [S=18940] [SID:981772393] ( lgr_flow)( 18580) | | | (#179)SIPSBCCallLeg <- (#179)AcSBCCallAPI: SBCSetupData
08:52:11.661 : 10.13.80.190 : Local 0 :NOTICE : [S=18941] [SID:981772393] ( sip_stack)( 18581) SDPBody::New - id = (#139)
08:52:11.718 : 10.13.80.190 : Local 0 :NOTICE : [S=18942] [SID:981772393] ( sip_stack)( 18582) SDPBodyMedia::New - id = (#1023)
08:52:11.719 : 10.13.80.190 : Local 0 :NOTICE : [S=18943] [SID:981772393] ( lgr_flow)( 18583) (#5) MediaMetaDataList Allocated.
08:52:11.772 : 10.13.80.190 : Local 0 :NOTICE : [S=18944] [SID:981772393] ( lgr_stk_ses)( 18584) SBCOfferAnswerMngr(#179) changes state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED
08:52:11.825 : 10.13.80.190 : Local 0 :NOTICE : [S=18945] [SID:981772393] ( media_connect)( 18585) (#142) MediaResourcesConnector Allocated.
08:52:11.826 : 10.13.80.190 : Local 0 :NOTICE : [S=18946] [SID:981772393] ( media_service)( 18586) (#307) MediaResourceAdapter Allocated.
08:52:11.883 : 10.13.80.190 : Local 0 :WARNING : [S=18947] [SID:981772393] ( lgr_stk_ses)( 18587) ?? [WARNING] SBCSDPFeatureMngr::HandleIncomingOffer - All medias are not supported.
08:52:11.937 : 10.13.80.190 : Local 0 :NOTICE : [S=18948] [SID:981772393] ( lgr_stk_ses)( 18588) SBCOfferAnswerMngr#179 - OfferAnswerCompleted not in SIP_MEDIA_COMPLETED state. Returning to previous state: SIP_MEDIA_IDLE
08:52:11.938 : 10.13.80.190 : Local 0 :NOTICE : [S=18949] [SID:981772393] ( sip_stack)( 18589) New SIPMessage created - (#161)
08:52:11.943 : 10.13.80.190 : Local 0 :NOTICE : [S=18950] [SID:981772393] ( lgr_flow)( 18590) | |(SIPTU(#562)DISCONNECT_REQ State:Invited(a6b070bfeafb56bf62922b8685bca4bc)
08:52:11.944 : 10.13.80.190 : Local 0 :NOTICE : [S=18951] [SID:981772393] ( lgr_flow)( 18591) ---- Outgoing SIP Message to 10.13.80.51:5060 from SIPInterface #1 UdpTransportObject(#1) ----
08:52:11.945 : 10.13.80.190 : Local 0 :NOTICE : [S=18952] [SID:981772393] SIP/2.0 488 Not Acceptable Here
Via: SIP/2.0/UDP 10.13.80.51:5060;received=10.13.80.51;rport=5060;branch=z9hG4bKd3a9d94ac91d17be6af171ade94e2472
From: "7011" <tel:+7011>;tag=941b2a0b46e2861f
To: <tel:+53306>;tag=1c1765771694
Call-ID: a6b070bfeafb56bf62922b8685bca4bc
CSeq: 1407010355 INVITE
Server: PRDUCGW1/v.6.80A.231.002
Reason: SIP ;cause=488 ;text="488 Not Acceptable Here"
Content-Length: 0
08:52:11.946 : 10.13.80.190 : Local 0 :NOTICE : [S=18953] [SID:981772393] ( sip_stack)( 18593) UdpRtxMngr::Transmit 488 Response 1407010355 INVITE Rtx Left: 6 Dest: 10.13.80.51:5060 CallID: (a6b070bfeafb56bf62922b8685bca4bc)
08:52:11.947 : 10.13.80.190 : Local 0 :NOTICE : [S=18954] [SID:981772393] ( sip_stack)( 18594) SIPCall(#562) changes state from Invited to Disconnected
08:52:11.948 : 10.13.80.190 : Local 0 :NOTICE : [S=18955] [SID:981772393] ( lgr_stk_ses)( 18595) SESSION (#179) SendToCall - event: RELEASE_EV m_Call(#426)
08:52:11.949 : 10.13.80.190 : Local 0 :NOTICE : [S=18956] [SID:981772393] ( lgr_flow)( 18596) | | (#426)SBCCall <- (#179)SIPSBCCallLeg: RELEASE_EV
08:52:11.950 : 10.13.80.190 : Local 0 :NOTICE : [S=18957] [SID:981772393] ( lgr_flow)( 18597) | | (#426) SBCCall changing states from:NewCallState to

08:52:11.951 : 10.13.80.190 : Local 0 :NOTICE : [S=18958] [SID:981772393] ( lgr_flow)( 18598) | (#425)SBCParticipantEndPoint <- (#426)SBCCall: RELEASE_EV
08:52:11.952 : 10.13.80.190 : Local 0 :NOTICE : [S=18959] [SID:981772393] ( lgr_flow)( 18599) (#425) SBCParticipantEndPoint changing states from:NewCallState to:ReleaseingState
08:52:11.952 : 10.13.80.190 : Local 0 :NOTICE : [S=18960] [SID:981772393] ( lgr_flow)( 18600) (#242)SBCController <- (#425)SBCParticipantEndPoint: RELEASE_EV
08:52:11.953 : 10.13.80.190 : Local 0 :NOTICE : [S=18961] [SID:981772393] ( lgr_flow)( 18601) | | (#242) SBCController changing states from:NewCallState to

08:52:11.955 : 10.13.80.190 : Local 0 :NOTICE : [S=18962] [SID:981772393] ( lgr_flow)( 18602) | (#426)SBCParticipantEndPoint <- (#242)SBCController: RELEASE_EV
08:52:11.956 : 10.13.80.190 : Local 0 :NOTICE : [S=18963] [SID:981772393] ( lgr_flow)( 18603) (#426) SBCParticipantEndPoint changing states from:NewCallState to:ReleaseingState
08:52:11.957 : 10.13.80.190 : Local 0 :NOTICE : [S=18964] [SID:981772393] ( lgr_flow)( 18604) | (#28)SBCCallPlacementFeature <- (#426)SBCParticipantEndPoint: RELEASE_EV
08:52:11.958 : 10.13.80.190 : Local 0 :NOTICE : [S=18965] [SID:981772393] ( lgr_flow)( 18605) (#28) SBCCallPlacementFeature changing states from: Idle to: Releasing
08:52:11.958 : 10.13.80.190 : Local 0 :NOTICE : [S=18966] [SID:981772393] ( lgr_flow)( 18606) | | (#425)SBCCall <- (#28)SBCCallPlacementFeature: RELEASE_EV
08:52:11.959 : 10.13.80.190 : Local 0 :NOTICE : [S=18967] [SID:981772393] ( lgr_flow)( 18607) | | (#425) SBCCall changing states from:NewCallState to

08:52:11.960 : 10.13.80.190 : Local 0 :NOTICE : [S=18968] [SID:981772393] ( lgr_flow)( 18608) | | | (#178)SIPSBCCallLeg <- (#425)SBCCall: RELEASE_EV
08:52:11.961 : 10.13.80.190 : Local 0 :NOTICE : [S=18969] [SID:981772393] ( sip_stack)( 18609) New SIPMessage created - (#163)
08:52:11.961 : 10.13.80.190 : Local 0 :WARNING : [S=18970] [SID:981772393] ( sip_stack)( 18610) !! [ERROR] AcSBCCallAPI:

08:52:11.962 : 10.13.80.190 : Local 0 :NOTICE : [S=18971] [SID:981772393] ( lgr_stk_ses)( 18611) SESSION (#178) SendToCall - event: RELEASE_ACK_EV m_Call(#425)
08:52:11.963 : 10.13.80.190 : Local 0 :NOTICE : [S=18972] [SID:981772393] ( lgr_stk_ses)( 18612) (#178)SIPSBCCallLeg Deallocated
08:52:11.963 : 10.13.80.190 : Local 0 :NOTICE : [S=18973] [SID:981772393] ( sip_stack)( 18613) Resource SIPMessage deleted - (#163)
08:52:11.964 : 10.13.80.190 : Local 0 :NOTICE : [S=18974] [SID:981772393] ( sip_stack)( 18614) Resource SIPMessage deleted - (#161)
08:52:11.965 : 10.13.80.190 : Local 0 :NOTICE : [S=18975] [SID:981772393] ( sip_stack)( 18615) SDPBody:

08:52:11.966 : 10.13.80.190 : Local 0 :NOTICE : [S=18976] [SID:981772393] ( sip_stack)( 18616) SDPBodyMedia:

08:52:11.967 : 10.13.80.190 : Local 0 :NOTICE : [S=18977] [SID:981772393] ( sip_stack)( 18617) Resource SIPMessage deleted - (#159)