09:53:55.666 10.151.3.14 local0.notice [S=491] [SID=58c3aa:27:9] ( lgr_flow)( 181) (#5345) gwSession Allocated. Handle:12DA18E8; Global session ID: 3369f8c25f75157a 09:53:55.667 10.151.3.14 local0.notice [S=492] [SID=58c3aa:27:9] ( sip_sbc)( 182) SIPSBCMngr::GetStackEP - No user is found in DB 09:53:55.667 10.151.3.14 local0.notice [S=493] [SID=58c3aa:27:9] ( lgr_sbc)( 183) (#119) SBCRoutesIterator Allocated. 09:53:55.668 10.151.3.14 local0.notice [S=494] [SID=58c3aa:27:9] ( lgr_sbc)( 184) Classification: Resolving to default IP group of SipInterface #0. 09:53:55.669 10.151.3.14 local0.notice [S=495] [SID=58c3aa:27:9] ( lgr_sbc)( 185) Classification Succeeded - Source IP Group #0 (Caller Addr), - Dest Routing Policy #0 09:53:55.669 10.151.3.14 local0.notice [S=496] [SID=58c3aa:27:9] ( lgr_flow)( 186) (#119)SBCRoutesIterator::Change State From: InitialCSRRouting To : InitialRouting 09:53:55.670 10.151.3.14 local0.notice [S=497] [SID=58c3aa:27:9] ( lgr_flow)( 187) (#119)SBCRoutesIterator::Change State From: InitialRouting To : AlternativeRouting 09:53:55.670 10.151.3.14 local0.notice [S=498] [SID=58c3aa:27:9] ( lgr_flow)( 188) (#-1)SBCAdmissionControlMngr <- (#119)SBCRoutesIterator: SBC_ADMIT_DIALOGS_EV 09:53:55.673 10.151.3.14 local0.notice [S=499] [SID=58c3aa:27:9] ( lgr_sbc)( 189) CallAdmission::AddDialog: Type INVITE LegType: SBC Direction In IP group 0 (Caller Addr): 1 SRD 0 (DefaultSRD): 1 SRD ovflw:1 SipInterface 0 (SBC): 1 used unres:1 09:53:55.673 10.151.3.14 local0.notice [S=500] [SID=58c3aa:27:9] ( media_service)( 190) ServicesMngr: Allocate SBC leg. current active: 0 and max is: 10 09:53:55.674 10.151.3.14 local0.notice [S=501] [SID=58c3aa:27:9] ( lgr_sbc)( 191) CallAdmission::AddDialog: Type INVITE LegType: SBC Direction Out IP group 1 (GW): 1 SRD 0 (DefaultSRD): 1 SRD ovflw:1 SipInterface 0 (SBC): 1 used unres:2 09:53:55.674 10.151.3.14 local0.notice [S=502] [SID=58c3aa:27:9] ( media_service)( 192) ServicesMngr: Allocate SBC leg. current active: 1 and max is: 10 09:53:55.675 10.151.3.14 local0.notice [S=503] [SID=58c3aa:27:9] ( lgr_flow)( 193) (#119)SBCRoutesIterator::Next route found: Rule #1, Route by: IPGroup , IP Group ID: 1 (GW), Live:True 09:53:55.676 10.151.3.14 local0.notice [S=504] [SID=58c3aa:27:9] ( lgr_sbc)( 194) Routing Succeeded -IP2IPRouting Rule #1 09:53:55.678 10.151.3.14 local0.notice [S=505] [SID=58c3aa:27:9] ( sip_stack)( 195) ---- Incoming SIP Message from 10.151.3.13:5060 to SIPInterface #0 (SBC) UDP TO(#0) ---- 09:53:55.679 10.151.3.14 local0.notice [S=506] [SID=58c3aa:27:9] INVITE sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.13:5060;branch=z9hG4bK-524287-1---4599362ea8638557;rport Max-Forwards: 70 Contact: To: From: "05333096548";tag=f11de856 Call-ID: 88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk CSeq: 1 INVITE Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE Content-Type: application/sdp Supported: replaces User-Agent: X-Lite release 5.0.3 stamp 88254 Content-Length: 209 v=0 o=- 13156325635369800 1 IN IP4 10.151.3.13 s=X-Lite release 5.0.3 stamp 88254 c=IN IP4 10.151.3.13 t=0 0 m=audio 64510 RTP/AVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv 09:53:55.679 10.151.3.14 local0.notice [S=507] [SID=58c3aa:27:9] ( sip_stack)( 196) New SIPMessage created - (#110) 09:53:55.680 10.151.3.14 local0.notice [S=508] [SID=58c3aa:27:9] ( sip_stack)( 197) New SIPSBCCallLeg created - (#4059) 09:53:55.681 10.151.3.14 local0.notice [S=509] [SID=58c3aa:27:9] ( sip_stack)( 198) New AcSIPCall created - (#4667) 09:53:55.683 10.151.3.14 local0.notice [S=510] [SID=58c3aa:27:9] ( sip_stack)( 199) AcTransactionUser::AddMessageToQueue: Queueing message 09:53:55.686 10.151.3.14 local0.notice [S=511] [SID=58c3aa:27:9] ( lgr_flow)( 200) | | | (#4059)SIPSBCCallLeg <- (#4059)AcSBCCallAPI: SBCNewCallData 09:53:55.686 10.151.3.14 local0.notice [S=512] [SID=58c3aa:27:9] ( lgr_call)( 201) (#27) CALL Allocated. 09:53:55.691 10.151.3.14 local0.notice [S=513] [SID=58c3aa:27:9] ( lgr_stk_ses)( 202) SESSION (#4059) SendToCall - event: NEW_CALL_EV m_Call(#27) 09:53:55.691 10.151.3.14 local0.notice [S=514] [SID=58c3aa:27:9] ( lgr_flow)( 203) | | (#27)SBCVoiceCall <- (#4059)SIPSBCCallLeg: NEW_CALL_EV 09:53:55.693 10.151.3.14 local0.notice [S=515] [SID=58c3aa:27:9] ( lgr_flow)( 204) | | (#27) SBCCall changing states from:IdleState to:NewCallState 09:53:55.694 10.151.3.14 local0.notice [S=516] [SID=58c3aa:27:9] ( lgr_call)( 205) Profiled: JB=0 JBMinDel=10 JBOptF=10 JBMaxDel=300 IPDS=46 SigIPDF=24 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 RBTid=-1 HTid=-1 ExtCGrp=-1 TMod=0 AllMedia= AllCGrp=-1 AllVCGrp=-1 ACdrM=0 MSBeh=0 MSMet=0 RTCPF=0 R2833B=0 AltDM=0 MltDTMF=0 AssrtID=1 DivM=0 HisIM=1 FaxCGId=-1 FaxBeh=0 FaxOffM=0 FaxAnsM=1 FaxReroutingM=0 PrkMod=3 RmtUpdSup=2 RmtRvtSup=2 RmtDOS=1 RmtRB=0 Rmt3B=2 RmtM18xS=1 RmtEMRT=0 RmtEMS=1 RmtRBSR=0 RmtSR3960=0 RmtCPRB=1 RmtHF=0 SDPPTA=0 PrfPT=0 USS=0 2833PT=0 RmtRepB=0 RmtMulEr=-1 RmtMulAns=0 RTPRedB=0 PlRbTr=0 AdptRfcBwVoiceBw=0 RTCPAttr=0 RemCryptoLT=0 Ice=0 RTCPMux=0 HandleXDetect=0 DMTag= SBCMaxCD=0 SBCGenRTP=0 VQ=0 OpusMaxBW=0 09:53:55.694 10.151.3.14 local0.notice [S=517] [SID=58c3aa:27:9] ( lgr_flow)( 206) ServicesMngr::GetSBCEndPoint PhoneNum = 1000 09:53:55.695 10.151.3.14 local0.notice [S=518] [SID=58c3aa:27:9] ( media_service)( 207) (#2038) CONTROLLER Allocated. 09:53:55.696 10.151.3.14 local0.notice [S=519] [SID=58c3aa:27:9] ( lgr_flow)( 208) (#4058)SBCParticipantEndPoint <- (#27)SBCVoiceCall: NEW_CALL_EV 09:53:55.697 10.151.3.14 local0.notice [S=520] [SID=58c3aa:27:9] ( lgr_flow)( 209) (#4058) SBCParticipantEndPoint changing states from:IdleState to:NewCallState 09:53:55.698 10.151.3.14 local0.notice [S=521] [SID=58c3aa:27:9] ( lgr_flow)( 210) (#2038)SBCController <- (#4058)SBCParticipantEndPoint: NEW_CALL_EV 09:53:55.698 10.151.3.14 local0.notice [S=522] [SID=58c3aa:27:9] ( lgr_flow)( 211) | | (#2038) SBCController changing states from:IdleState to:NewCallState 09:53:55.699 10.151.3.14 local0.notice [S=523] [SID=58c3aa:27:9] ( lgr_flow)( 212) (#4059)SBCParticipantEndPoint <- (#2038)SBCController: NEW_CALL_EV 09:53:55.700 10.151.3.14 local0.notice [S=524] [SID=58c3aa:27:9] ( lgr_flow)( 213) (#4059) SBCParticipantEndPoint changing states from:IdleState to:NewCallState 09:53:55.700 10.151.3.14 local0.notice [S=525] [SID=58c3aa:27:9] ( lgr_sbc)( 214) (#129) FEATURE Allocated. 09:53:55.703 10.151.3.14 local0.notice [S=526] [SID=58c3aa:27:9] ( lgr_flow)( 215) | (#129)SBCCallPlacementFeature <- (#4059)SBCParticipantEndPoint: NEW_CALL_EV 09:53:55.703 10.151.3.14 local0.notice [S=527] [SID=58c3aa:27:9] ( lgr_call)( 216) (#26) CALL Allocated. 09:53:55.704 10.151.3.14 local0.notice [S=528] [SID=58c3aa:27:9] ( lgr_flow)( 217) | | (#26)SBCVoiceCall <- (#129)SBCCallPlacementFeature: NEW_CALL_EV 09:53:55.704 10.151.3.14 local0.notice [S=529] [SID=58c3aa:27:9] ( lgr_flow)( 218) | | (#26) SBCCall changing states from:IdleState to:NewCallState 09:53:55.705 10.151.3.14 local0.notice [S=530] [SID=58c3aa:27:9] ( lgr_call)( 219) Profiled: JB=0 JBMinDel=10 JBOptF=10 JBMaxDel=300 IPDS=46 SigIPDF=24 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 RBTid=-1 HTid=-1 ExtCGrp=-1 TMod=0 AllMedia= AllCGrp=-1 AllVCGrp=-1 ACdrM=0 MSBeh=0 MSMet=0 RTCPF=0 R2833B=0 AltDM=0 MltDTMF=0 AssrtID=1 DivM=0 HisIM=1 FaxCGId=-1 FaxBeh=0 FaxOffM=0 FaxAnsM=1 FaxReroutingM=0 PrkMod=3 RmtUpdSup=2 RmtRvtSup=2 RmtDOS=1 RmtRB=0 Rmt3B=2 RmtM18xS=1 RmtEMRT=0 RmtEMS=1 RmtRBSR=0 RmtSR3960=0 RmtCPRB=1 RmtHF=0 SDPPTA=0 PrfPT=0 USS=0 2833PT=0 RmtRepB=0 RmtMulEr=-1 RmtMulAns=0 RTPRedB=0 PlRbTr=0 AdptRfcBwVoiceBw=0 RTCPAttr=0 RemCryptoLT=0 Ice=0 RTCPMux=0 HandleXDetect=0 DMTag= SBCMaxCD=0 SBCGenRTP=0 VQ=0 OpusMaxBW=0 09:53:55.709 10.151.3.14 local0.notice [S=531] [SID=58c3aa:27:9] ( lgr_stk_mngr)( 220) (#4058)SIPSBCCallLeg Allocated. 09:53:55.709 10.151.3.14 local0.notice [S=532] [SID=58c3aa:27:9] ( lgr_flow)( 221) | | | (#4058)SIPSBCCallLeg <- (#26)SBCVoiceCall: NEW_CALL_EV 09:53:55.710 10.151.3.14 local0.notice [S=533] [SID=58c3aa:27:9] ( sip_stack)( 222) AcTransactionUser::HandleQueuedMessages: Handle a queued message 09:53:55.714 10.151.3.14 local0.notice [S=534] [SID=58c3aa:27:9] ( lgr_flow)( 223) | |(SIPTU(#4667)INVITE State:Idle(88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk) 09:53:55.718 10.151.3.14 local0.notice [S=535] [SID=58c3aa:27:9] ( sip_sbc)( 224) SIPSBCMngr::GetStackEP - No user is found in DB 09:53:55.718 10.151.3.14 local0.notice [S=536] [SID=58c3aa:27:9] ( sip_stack)( 225) TU(#4667) SIPSessionTimer::HandleSTRequest - Session-Timer mode: TRANSPARENT 09:53:55.719 10.151.3.14 local0.notice [S=537] [SID=58c3aa:27:9] ( sip_stack)( 226) SIPCall(#4667) changes state from Idle to Invited 09:53:55.720 10.151.3.14 local0.notice [S=538] [SID=58c3aa:27:9] ( lgr_flow)( 227) | | | (#4059)SIPSBCCallLeg <- (#4059)AcSBCCallAPI: SBCSetupData 09:53:55.721 10.151.3.14 local0.notice [S=539] [SID=58c3aa:27:9] ( sip_stack)( 228) SDPBody::New - id = (#54) 09:53:55.721 10.151.3.14 local0.notice [S=540] [SID=58c3aa:27:9] ( sip_stack)( 229) SDPBodyMedia::New - id = (#467) 09:53:55.724 10.151.3.14 local0.notice [S=541] [SID=58c3aa:27:9] ( lgr_flow)( 230) (#149) MediaMetaDataList Allocated. 09:53:55.724 10.151.3.14 local0.notice [S=542] [SID=58c3aa:27:9] ( lgr_stk_ses)( 231) SBCOfferAnswerMngr(#4059) changes state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 09:53:55.725 10.151.3.14 local0.notice [S=543] [SID=58c3aa:27:9] ( media_connect)( 232) (#4) MediaResourcesConnector Allocated. 09:53:55.725 10.151.3.14 local0.notice [S=544] [SID=58c3aa:27:9] ( media_service)( 233) (#9) MediaResourceAdapter Allocated. OrigSide IP 09:53:55.726 10.151.3.14 local0.notice [S=545] [SID=58c3aa:27:9] ( lgr_stk_ses)( 234) SBCSDPDTMFFeature::UpdateMediaDTMFConfig: m_RFC=0 m_Alternative=0 m_PeerRFC=0 m_PeerAlternative=0 m_SendMultipleDTMFMethods=False m_PeerSendMultipleDTMFMethods=False 09:53:55.726 10.151.3.14 local0.notice [S=546] [SID=58c3aa:27:9] ( lgr_stk_ses)( 235) SBCSDPANATFeature::UpdateANATConfig - Behavior isIP_VERSION_ONLY_IPV4 09:53:55.728 10.151.3.14 local0.notice [S=547] [SID=58c3aa:27:9] ( media_service)( 236) (#19) RTPStreamResource Allocated. Sibling ID: (#-1) 09:53:55.729 10.151.3.14 local0.notice [S=548] [SID=58c3aa:27:9] ( media_service)( 237) (#9) ChannelResource Allocated. 09:53:55.730 10.151.3.14 local0.notice [S=549] [SID=58c3aa:27:9] ( media_service)( 238) (#9) ChannelResource belongs to call #27 09:53:55.730 10.151.3.14 local0.notice [S=550] [SID=58c3aa:27:9] ( lgr_flow)( 239) (#9)ChannelResource::AllocateMediaIpPorts RealmIndex(0) port(6000) Allocated. 09:53:55.732 10.151.3.14 local0.notice [S=551] [SID=58c3aa:27:9] ( lgr_stk_mngr)( 240) New BufferSDPBody created - (#178) 09:53:55.732 10.151.3.14 local0.notice [S=552] [SID=58c3aa:27:9] ( sip_stack)( 241) SDPBodyMedia::New - id = (#466) 09:53:55.733 10.151.3.14 local0.notice [S=553] [SID=58c3aa:27:9] ( lgr_flow)( 242) | |(SIPTU(#4667)TRYING_REQ State:Invited(88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk) 09:53:55.734 10.151.3.14 local0.notice [S=554] [SID=58c3aa:27:9] ( sip_stack)( 243) New SIPMessage created - (#109) 09:53:55.735 10.151.3.14 local0.notice [S=555] [SID=58c3aa:27:9] ( sip_stack)( 244) ---- Outgoing SIP Message to 10.151.3.13:5060 from SIPInterface #0 (SBC) UDP TO(#0) ---- 09:53:55.736 10.151.3.14 local0.notice [S=556] [SID=58c3aa:27:9] SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.151.3.13:5060;received=10.151.3.13;rport=5060;branch=z9hG4bK-524287-1---4599362ea8638557 From: "05333096548" ;tag=f11de856 To: Call-ID: 88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk CSeq: 1 INVITE Server: M500/v.7.20A.154.052 Content-Length: 0 09:53:55.740 10.151.3.14 local0.notice [S=557] [SID=58c3aa:27:9] ( lgr_flow)( 245) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:53:55.741 10.151.3.14 local0.notice [S=558] [SID=58c3aa:27:9] ( sip_stack)( 246) Resource SIPMessage deleted - (#109) 09:53:55.742 10.151.3.14 local0.notice [S=559] [SID=58c3aa:27:9] ( lgr_flow)( 247) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:53:55.746 10.151.3.14 local0.notice [S=560] [SID=58c3aa:27:9] ( lgr_stk_ses)( 248) SESSION (#4059) SendToCall - event: SETUP_EV m_Call(#27) 09:53:55.746 10.151.3.14 local0.notice [S=561] [SID=58c3aa:27:9] ( lgr_flow)( 249) | | (#27)SBCVoiceCall <- (#4059)SIPSBCCallLeg: SETUP_EV 09:53:55.748 10.151.3.14 local0.notice [S=562] [SID=58c3aa:27:9] ( lgr_flow)( 250) | | (#27) SBCCall changing states from:NewCallState to:InitiatedState 09:53:55.748 10.151.3.14 local0.notice [S=563] [SID=58c3aa:27:9] ( lgr_flow)( 251) (#4058)SBCParticipantEndPoint <- (#27)SBCVoiceCall: SETUP_EV 09:53:55.749 10.151.3.14 local0.notice [S=564] [SID=58c3aa:27:9] ( lgr_flow)( 252) (#4058) SBCParticipantEndPoint changing states from:NewCallState to:InitiatedState 09:53:55.750 10.151.3.14 local0.notice [S=565] [SID=58c3aa:27:9] ( lgr_flow)( 253) (#2038)SBCController <- (#4058)SBCParticipantEndPoint: SETUP_EV 09:53:55.751 10.151.3.14 local0.notice [S=566] [SID=58c3aa:27:9] ( lgr_flow)( 254) | | (#2038) SBCController changing states from:NewCallState to:EstablishingState 09:53:55.753 10.151.3.14 local0.notice [S=567] [SID=58c3aa:27:9] ( lgr_flow)( 255) MediaResourcesConnector(#4)Handle SBCMediaCarrier from Controller 09:53:55.753 10.151.3.14 local0.notice [S=568] [SID=58c3aa:27:9] ( lgr_flow)( 256) (#4059)SBCParticipantEndPoint <- (#2038)SBCController: SETUP_EV 09:53:55.754 10.151.3.14 local0.notice [S=569] [SID=58c3aa:27:9] ( lgr_flow)( 257) (#4059) SBCParticipantEndPoint changing states from:NewCallState to:InitiatedState 09:53:55.755 10.151.3.14 local0.notice [S=570] [SID=58c3aa:27:9] ( lgr_flow)( 258) | (#129)SBCCallPlacementFeature <- (#4059)SBCParticipantEndPoint: SETUP_EV 09:53:55.756 10.151.3.14 local0.notice [S=571] [SID=58c3aa:27:9] ( lgr_flow)( 259) (#148) MediaMetaDataList Allocated. 09:53:55.757 10.151.3.14 local0.notice [S=572] [SID=58c3aa:27:9] ( lgr_flow)( 260) (#129) SBCCallPlacementFeature changing states from: Idle to: Initiated 09:53:55.758 10.151.3.14 local0.notice [S=573] [SID=58c3aa:27:9] ( lgr_flow)( 261) | | (#26)SBCVoiceCall <- (#129)SBCCallPlacementFeature: SETUP_EV 09:53:55.759 10.151.3.14 local0.notice [S=574] [SID=58c3aa:27:9] ( sip_stack)( 262) SDPBody::New - id = (#53) 09:53:55.759 10.151.3.14 local0.notice [S=575] [SID=58c3aa:27:9] ( sip_stack)( 263) SDPBodyMedia::New - id = (#465) 09:53:55.760 10.151.3.14 local0.notice [S=576] [SID=58c3aa:27:9] ( lgr_flow)( 264) | | (#26) SBCCall changing states from:NewCallState to:InitiatedState 09:53:55.760 10.151.3.14 local0.notice [S=577] [SID=58c3aa:27:9] ( lgr_flow)( 265) | | | (#4058)SIPSBCCallLeg <- (#26)SBCVoiceCall: SETUP_EV 09:53:55.761 10.151.3.14 local0.notice [S=578] [SID=58c3aa:27:9] ( lgr_stk_ses)( 266) SIPSBCCallLeg(#4058) - Route by dest IPGroup: #1 09:53:55.763 10.151.3.14 local0.notice [S=579] [SID=58c3aa:27:9] ( lgr_flow)( 267) | | new AcSIPCall created - (#4666) 09:53:55.764 10.151.3.14 local0.notice [S=580] [SID=58c3aa:27:9] ( lgr_stk_ses)( 268) SBCOfferAnswerMngr(#4058) changes state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERING 09:53:55.766 10.151.3.14 local0.notice [S=581] [SID=58c3aa:27:9] ( media_service)( 269) (#8) MediaResourceAdapter Allocated. OrigSide TEL 09:53:55.768 10.151.3.14 local0.notice [S=582] [SID=58c3aa:27:9] ( lgr_stk_ses)( 270) SBCSDPDTMFFeature::UpdateMediaDTMFConfig: m_RFC=0 m_Alternative=0 m_PeerRFC=0 m_PeerAlternative=0 m_SendMultipleDTMFMethods=False m_PeerSendMultipleDTMFMethods=False 09:53:55.771 10.151.3.14 local0.notice [S=583] [SID=58c3aa:27:9] ( lgr_stk_ses)( 271) SBCSDPANATFeature::UpdateANATConfig - Behavior isIP_VERSION_ONLY_IPV4 09:53:55.774 10.151.3.14 local0.notice [S=584] [SID=58c3aa:27:9] ( media_service)( 272) (#18) RTPStreamResource Allocated. Sibling ID: (#-1) 09:53:55.774 10.151.3.14 local0.notice [S=585] [SID=58c3aa:27:9] ( media_service)( 273) (#8) ChannelResource Allocated. 09:53:55.776 10.151.3.14 local0.notice [S=586] [SID=58c3aa:27:9] ( media_service)( 274) (#8) ChannelResource belongs to call #26 09:53:55.776 10.151.3.14 local0.notice [S=587] [SID=58c3aa:27:9] ( lgr_flow)( 275) (#8)ChannelResource::AllocateMediaIpPorts RealmIndex(0) port(6010) Allocated. 09:53:55.777 10.151.3.14 local0.notice [S=588] [SID=58c3aa:27:9] ( lgr_flow)( 276) #MediaResourcesConnector::AllocateMediaResources 09:53:55.777 10.151.3.14 local0.notice [S=589] [SID=58c3aa:27:9] ( media_connect)( 277) ConnectionData::CalculateResourcesForRTPForwarding Leading:NONE Opposite:NONE MediationLevel:RTP 09:53:55.778 10.151.3.14 local0.notice [S=590] [SID=58c3aa:27:9] ( lgr_flow)( 278) #MediaResourcesConnector::AllocateMediaResources 09:53:55.779 10.151.3.14 local0.notice [S=591] [SID=58c3aa:27:9] ( media_connect)( 279) ConnectionData::CalculateResourcesForRTPForwarding Leading:NONE Opposite:NONE MediationLevel:RTP 09:53:55.780 10.151.3.14 local0.notice [S=592] [SID=58c3aa:27:9] ( lgr_stk_ses)( 280) DirectMediaDB::StoreSDP added DirectMediaData to Database IP:10.151.3.14 Port:6010 SDP:(#178) 09:53:55.780 10.151.3.14 local0.notice [S=593] [SID=58c3aa:27:9] ( lgr_flow)( 281) (#147) MediaMetaDataList Allocated. 09:53:55.781 10.151.3.14 local0.notice [S=594] [SID=58c3aa:27:9] ( lgr_stk_mngr)( 282) New BufferSDPBody created - (#177) 09:53:55.783 10.151.3.14 local0.notice [S=595] [SID=58c3aa:27:9] ( sip_stack)( 283) SDPBodyMedia::New - id = (#464) 09:53:55.784 10.151.3.14 local0.notice [S=596] [SID=58c3aa:27:9] ( sip_sbc)( 284) SIPSBCMngr::GetStackEP - No user is found in DB 09:53:55.784 10.151.3.14 local0.notice [S=597] [SID=58c3aa:27:9] ( lgr_flow)( 285) | |(SIPTU(#4666)SETUP_REQ State:Idle() 09:53:55.785 10.151.3.14 local0.notice [S=598] [SID=58c3aa:27:9] ( sip_stack)( 286) SIPCall(#4666) changes state from Idle to Inviting 09:53:55.786 10.151.3.14 local0.notice [S=599] [SID=58c3aa:27:9] ( sip_stack)( 287) TU(#4666) SIPSessionTimer::FillSTRequestData - Session-Timer mode: TRANSPARENT 09:53:55.786 10.151.3.14 local0.notice [S=600] [SID=58c3aa:27:9] ( lgr_stack)( 288) [ManSet: 1, Rule: 2] - Executed on msgId:110 (Condition was False) 09:53:55.788 10.151.3.14 local0.notice [S=601] [SID=58c3aa:27:9] ( sip_stack)( 289) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 6 Dest: 10.151.3.14:5070 CallID: (394224111120100315@10.151.3.14) 09:53:55.789 10.151.3.14 local0.notice [S=602] [SID=58c3aa:27:9] ( sip_stack)( 290) ---- Outgoing SIP Message to 10.151.3.14:5070 from SIPInterface #0 (SBC) UDP TO(#0) ---- 09:53:55.789 10.151.3.14 local0.notice [S=603] [SID=58c3aa:27:9] INVITE sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1808763847 Max-Forwards: 69 From: "05333096548" ;tag=1c1622334087 To: Call-ID: 394224111120100315@10.151.3.14 CSeq: 1 INVITE Contact: Supported: replaces,sdp-anat Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE User-Agent: M500/v.7.20A.154.052 P-Asserted-Identity: Content-Type: application/sdp Content-Length: 210 v=0 o=- 1459874283 1675414285 IN IP4 10.151.3.14 s=X-Lite release 5.0.3 stamp 88254 c=IN IP4 10.151.3.14 t=0 0 m=audio 6010 RTP/AVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv 09:53:55.790 10.151.3.14 local0.notice [S=604] [SID=58c3aa:27:9] ( lgr_flow)( 291) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:53:55.791 10.151.3.14 local0.notice [S=605] [SID=58c3aa:27:9] ( lgr_flow)( 292) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:53:55.792 10.151.3.14 local0.notice [S=606] [SID=58c3aa:27:9] ( sip_stack)( 293) SDPBody::Delete - id = (#53) 09:53:55.796 10.151.3.14 local0.notice [S=607] [SID=58c3aa:27:9] ( sip_stack)( 294) SDPBodyMedia::Delete - id = (#465) 09:53:55.796 10.151.3.14 local0.notice [S=608] [SID=58c3aa:27:10] ( lgr_flow)( 295) (#5344) gwSession Allocated. Handle:12DA1908; Global session ID: 61cebb6719f463d2 09:53:55.797 10.151.3.14 local0.notice [S=609] [SID=58c3aa:27:10] ( sip_stack)( 296) ---- Incoming SIP Message from 10.151.3.14:5060 to SIPInterface #1 (GW) UDP TO(#1) ---- 09:53:55.801 10.151.3.14 local0.notice [S=610] [SID=58c3aa:27:10] INVITE sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1808763847 Max-Forwards: 69 From: "05333096548" ;tag=1c1622334087 To: Call-ID: 394224111120100315@10.151.3.14 CSeq: 1 INVITE Contact: Supported: replaces,sdp-anat Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE User-Agent: M500/v.7.20A.154.052 P-Asserted-Identity: Content-Type: application/sdp Content-Length: 210 v=0 o=- 1459874283 1675414285 IN IP4 10.151.3.14 s=X-Lite release 5.0.3 stamp 88254 c=IN IP4 10.151.3.14 t=0 0 m=audio 6010 RTP/AVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv 09:53:55.801 10.151.3.14 local0.notice [S=611] [SID=58c3aa:27:10] ( sip_stack)( 297) New SIPMessage created - (#108) 09:53:55.803 10.151.3.14 local0.notice [S=612] [SID=58c3aa:27:10] ( sip_stack)( 298) New AcSIPCallAPI created - (#27) + New SIPStackSession created - (#20) 09:53:55.804 10.151.3.14 local0.notice [S=613] [SID=58c3aa:27:10] ( sip_stack)( 299) New AcSIPCall created - (#4665) 09:53:55.804 10.151.3.14 local0.notice [S=614] [SID=58c3aa:27:10] ( lgr_flow)( 300) | |(SIPTU(#4665)INVITE State:Idle(394224111120100315@10.151.3.14) 09:53:55.805 10.151.3.14 local0.notice [S=615] [SID=58c3aa:27:10] ( sip_sbc)( 301) SIPSBCMngr::GetStackEP - No user is found in DB 09:53:55.807 10.151.3.14 local0.notice [S=616] [SID=58c3aa:27:10] ( sip_stack)( 302) TU(#4665) SIPSessionTimer::HandleSTRequest - Session-Timer mode: SUPPORTED 09:53:55.808 10.151.3.14 local0.notice [S=617] [SID=58c3aa:27:10] ( sip_stack)( 303) SIPCall(#4665) changes state from Idle to Invited 09:53:55.810 10.151.3.14 local0.notice [S=618] [SID=58c3aa:27:10] ( sip_stack)( 304) SDPBody::New - id = (#52) 09:53:55.811 10.151.3.14 local0.notice [S=619] [SID=58c3aa:27:10] ( sip_stack)( 305) SDPBodyMedia::New - id = (#463) 09:53:55.811 10.151.3.14 local0.notice [S=620] [SID=58c3aa:27:10] ( lgr_flow)( 306) | | | (#20)SIPStackSession <- (#27)AcSIPCallAPI: SIPSetupData 09:53:55.813 10.151.3.14 local0.notice [S=621] [SID=58c3aa:27:10] ( lgr_call)( 307) (#25) CALL Allocated. 09:53:55.814 10.151.3.14 local0.notice [S=622] [SID=58c3aa:27:10] ( sip_sbc)( 308) SIPSBCMngr::GetStackEP - No user is found in DB 09:53:55.814 10.151.3.14 local0.notice [S=623] [SID=58c3aa:27:10] ( lgr_stk_ses)( 309) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 09:53:55.815 10.151.3.14 local0.notice [S=624] [SID=58c3aa:27:10] ( lgr_stk_ses)( 310) SESSION (#20) SendToCall - event: NEW_CALL_EV m_Call(#25) 09:53:55.816 10.151.3.14 local0.notice [S=625] [SID=58c3aa:27:10] ( lgr_flow)( 311) | | (#25):NEW_CALL_EV:(394224111120100315@10.151.3.14) 09:53:55.816 10.151.3.14 local0.notice [S=626] [SID=58c3aa:27:10] ( lgr_flow)( 312) | | (#25):Call changing states from:IdleState to:NewCallState_IP2Tel 09:53:55.818 10.151.3.14 local0.notice [S=627] [SID=58c3aa:27:10] ( lgr_flow)( 313) ServicesMngr::GetEndPoint PhoneNum = 1000 09:53:55.818 10.151.3.14 local0.notice [S=628] [SID=58c3aa:27:10] ( lgr_gw_engine)( 314) GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:1000 DstPfx:* SrcNum:05333096548 SrcPfx:* SrcIp:177668878 SrcIpPfx: SrcIpGroup:-1 09:53:55.820 10.151.3.14 local0.notice [S=629] [SID=58c3aa:27:10] ( lgr_psbrdif)( 315) Current trunks status: 09:53:55.824 10.151.3.14 local0.notice [S=630] [SID=58c3aa:27:10] ( lgr_call)( 316) Call::SetProfiledCallParams (#25) Found 4 Common Coders For Call 09:53:55.824 10.151.3.14 local0.notice [S=631] [SID=58c3aa:27:10] ( lgr_call)( 317) Call (#25) Coder g711Alaw64k20 : 20 09:53:55.825 10.151.3.14 local0.notice [S=632] [SID=58c3aa:27:10] ( lgr_call)( 318) Call (#25) Coder g711Ulaw64k20 : 20 09:53:55.829 10.151.3.14 local0.notice [S=633] [SID=58c3aa:27:10] ( lgr_call)( 319) Call (#25) Coder g72920 : 20 09:53:55.829 10.151.3.14 local0.notice [S=634] [SID=58c3aa:27:10] ( lgr_call)( 320) Call (#25) Coder g72220 : 20 09:53:55.830 10.151.3.14 local0.notice [S=635] [SID=58c3aa:27:10] ( lgr_profiling)( 321) Profiled: JBMinDel=10 JBOptF=10 JBMaxDel=300 IPDS=46 SigIPDF=24 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 RBTid=-1 HTid=-1 EEarlyM=1 FaxTM=1 IsFaxU=0 PI2IP=-1 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 VxxTT=2 Dst2Rdrt=0 DTMFVol=20 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 MWIA=0 MWID=0 MWITO=0 ReorderTime=255 DIDWink=0 2StageDial=1 DiscOnBusyT=1 DPInd=-1 AGC=0 NLP=0 PBT2ISDN=0 09:53:55.831 10.151.3.14 local0.notice [S=636] [SID=58c3aa:27:10] ( lgr_flow)( 322) | #0:NEW_CALL_EV : (394224111120100315@10.151.3.14) 09:53:55.833 10.151.3.14 local0.notice [S=637] [SID=58c3aa:27:10] ( lgr_stk_mngr)( 323) New BufferSDPBody created - (#176) 09:53:55.833 10.151.3.14 local0.notice [S=638] [SID=58c3aa:27:10] ( lgr_flow)( 324) EndPoint::MediaResourceList::AllocateMediaIpPortsByMediaRealmID Perform NEW allocation of Media ports for RealmIndex(0) port(6020) current allocations are:(1) Owner:((#0)) 09:53:55.834 10.151.3.14 local0.notice [S=639] [SID=58c3aa:27:10] ( sip_stack)( 325) SDPBodyMedia::New - id = (#462) 09:53:55.835 10.151.3.14 local0.notice [S=640] [SID=58c3aa:27:10] ( sip_stack)( 326) SDPBodyMedia::Delete - id = (#462) 09:53:55.836 10.151.3.14 local0.notice [S=641] [SID=58c3aa:27:10] ( sip_stack)( 327) Resource BufferSDPBody deleted - (#176) 09:53:55.836 10.151.3.14 local0.notice [S=642] [SID=58c3aa:27:10] ( lgr_stk_mngr)( 328) New BufferSDPBody created - (#175) 09:53:55.838 10.151.3.14 local0.notice [S=643] [SID=58c3aa:27:10] ( sip_stack)( 329) SDPBodyMedia::New - id = (#461) 09:53:55.839 10.151.3.14 local0.notice [S=644] [SID=58c3aa:27:10] ( sip_stack)( 330) SIPSDPSession(#20) - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 09:53:55.839 10.151.3.14 local0.notice [S=645] [SID=58c3aa:27:10] ( sip_stack)( 331) UpdateChosenMediaByCN - CN as Remote False 09:53:55.840 10.151.3.14 local0.notice [S=646] [SID=58c3aa:27:10] ( sip_stack)( 332) SDPBodyMedia::Delete - id = (#461) 09:53:55.841 10.151.3.14 local0.notice [S=647] [SID=58c3aa:27:10] ( sip_stack)( 333) SDPBodyMedia::New - id = (#460) 09:53:55.841 10.151.3.14 local0.notice [S=648] [SID=58c3aa:27:10] ( lgr_flow)( 334) | |(SIPTU(#4665)TRYING_REQ State:Invited(394224111120100315@10.151.3.14) 09:53:55.843 10.151.3.14 local0.notice [S=649] [SID=58c3aa:27:10] ( sip_stack)( 335) New SIPMessage created - (#107) 09:53:55.844 10.151.3.14 local0.notice [S=650] [SID=58c3aa:27:10] ( sip_stack)( 336) ---- Outgoing SIP Message to 10.151.3.14:5060 from SIPInterface #1 (GW) UDP TO(#1) ---- 09:53:55.845 10.151.3.14 local0.notice [S=651] [SID=58c3aa:27:10] SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1808763847 From: "05333096548" ;tag=1c1622334087 To: Call-ID: 394224111120100315@10.151.3.14 CSeq: 1 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: M500/v.7.20A.154.052 Content-Length: 0 09:53:55.845 10.151.3.14 local0.notice [S=652] [SID=58c3aa:27:10] ( lgr_flow)( 337) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:53:55.846 10.151.3.14 local0.notice [S=653] [SID=58c3aa:27:10] ( sip_stack)( 338) Resource SIPMessage deleted - (#107) 09:53:55.850 10.151.3.14 local0.notice [S=654] [SID=58c3aa:27:10] ( lgr_flow)( 339) (#252)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:53:55.851 10.151.3.14 local0.notice [S=655] [SID=58c3aa:27:10] ( lgr_stk_ses)( 340) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 09:53:55.851 10.151.3.14 local0.notice [S=656] [SID=58c3aa:27:10] ( lgr_stk_ses)( 341) SESSION (#20) SendToCall - event: SETUP_EV m_Call(#25) 09:53:55.862 10.151.3.14 local0.notice [S=657] [SID=58c3aa:27:10] ( lgr_flow)( 342) | | (#25):SETUP (TO:1000, FROM:05333096548):(394224111120100315@10.151.3.14) 09:53:55.863 10.151.3.14 local0.notice [S=658] [SID=58c3aa:27:10] ( lgr_flow)( 343) | | (#25):Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel 09:53:55.864 10.151.3.14 local0.notice [S=659] [SID=58c3aa:27:10] ( lgr_flow)( 344) | #0:SETUP_EV : (394224111120100315@10.151.3.14) 09:53:55.865 10.151.3.14 local0.notice [S=660] [SID=58c3aa:27:10] ( lgr_flow)( 345) | #0:FWD_CALL_EV (send) : (394224111120100315@10.151.3.14) 09:53:55.865 10.151.3.14 local0.notice [S=661] [SID=58c3aa:27:10] ( lgr_flow)( 346) | | (#25):FWD_CALL_EV:(394224111120100315@10.151.3.14) 09:53:55.866 10.151.3.14 local0.notice [S=662] [SID=58c3aa:27:10] ( lgr_flow)( 347) | | | (#20)SIPStackSession <- (#0)FXSEndPoint: FWD_CALL_EV (394224111120100315@10.151.3.14) 09:53:55.868 10.151.3.14 local0.notice [S=663] [SID=58c3aa:27:10] ( sip_stack)( 348) New SIPMessage created - (#105) 09:53:55.868 10.151.3.14 local0.notice [S=664] [SID=58c3aa:27:10] ( lgr_routing)( 349) CachedGwRoutingPolicy sorted table at time FRI:00:03:15, next update SUN:00:00:00 09:53:55.869 10.151.3.14 local0.notice [S=665] [SID=58c3aa:27:10] ( lgr_routing)( 350) Sorted Prefix Table Line 0 Index 0 Dest Prefix * Dest Addr 10.147.253.165 Cost Group -1 09:53:55.870 10.151.3.14 local0.notice [S=666] [SID=58c3aa:27:10] ( lgr_flow)( 351) | |(SIPTU(#4665)FORWARD_REQ State:Invited(394224111120100315@10.151.3.14) 09:53:55.871 10.151.3.14 local0.notice [S=667] [SID=58c3aa:27:10] ( sip_stack)( 352) SIPCall(#4665) changes state from Invited to Disconnected 09:53:55.871 10.151.3.14 local0.notice [S=668] [SID=58c3aa:27:10] ( lgr_stack)( 353) [ManSet: 1, Rule: 0] - Executed on msgId:105 (Condition was True) 09:53:55.873 10.151.3.14 local0.notice [S=669] [SID=58c3aa:27:10] ( sip_stack)( 354) UdpRtxMngr::Transmit 302 Response 1 INVITE Rtx Left: 6 Dest: 10.151.3.14:5060 CallID: (394224111120100315@10.151.3.14) 09:53:55.874 10.151.3.14 local0.notice [S=670] [SID=58c3aa:27:10] ( sip_stack)( 355) ---- Outgoing SIP Message to 10.151.3.14:5060 from SIPInterface #1 (GW) UDP TO(#1) ---- 09:53:55.875 10.151.3.14 local0.notice [S=671] [SID=58c3aa:27:10] SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1808763847 From: "05333096548" ;tag=1c1622334087 To: ;tag=1c904537791 Call-ID: 394224111120100315@10.151.3.14 CSeq: 1 INVITE Contact: Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Diversion: ;reason=unconditional;counter=1 Server: M500/v.7.20A.154.052 Reason: SIP ;cause=302 ;text="302 Moved Temporarily" Content-Length: 0 09:53:55.878 10.151.3.14 local0.notice [S=672] [SID=58c3aa:27:10] ( lgr_flow)( 356) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:53:55.879 10.151.3.14 local0.notice [S=673] [SID=58c3aa:27:10] ( lgr_stk_ses)( 357) SESSION (#20) SendToCall - event: RELEASE_EV m_Call(#25) 09:53:55.880 10.151.3.14 local0.notice [S=674] [SID=58c3aa:27:10] ( lgr_flow)( 358) (#252)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:53:55.880 10.151.3.14 local0.notice [S=675] [SID=58c3aa:27:10] ( lgr_flow)( 359) | | (#25):RELEASE_EV:(394224111120100315@10.151.3.14) 09:53:55.881 10.151.3.14 local0.notice [S=676] [SID=58c3aa:27:10] ( lgr_flow)( 360) | | (#25):Call changing states from:InitiatedState_IP2Tel to:DisconnectingState 09:53:55.883 10.151.3.14 local1.info [S=1] |GWReportType |Cid |SessionId |LegId|Trunk|BChan|ConId|TG |EPTyp |Orig |SourceIp |DestIp |TON |NPI |SrcPhoneNum |SrcNumBeforeMap |TON |NPI |DstPhoneNum |DstNumBeforeMap |Durat|Coder |Intrv|RtpIp |Port |TrmSd|TrmReason |Fax |InPackets |OutPackets|PackLoss |RemotePackLoss|SIPCallId |SetupTime |ConnectTime |ReleaseTime |RTPdelay |RTPjitter|RTPssrc |RemoteRTPssrc |RedirectReason |TON |NPI |RedirectPhonNum |MeteringPulses |SrcHost |SrcHostBeforeMap |DstHost |DstHostBeforeMap |IPG (name) |LocalRtpIp |LocalRtpPort |Amount |Mult |TrmReasonCategory|RedirectNumBeforeMap|SrdId (name) |SIPInterfaceId (name) |ProxySetId (name) |IpProfileId (name) |MediaRealmId (name) |SigTransportType|TxRTPIPDiffServ|TxSigIPDiffServ|LocalRFactor|RemoteRFactor|LocalMosCQ|RemoteMosCQ|SigSourcePort|SigDestPort|MediaType |AMD| % |SIPTrmReason|SIPTermDe 09:53:55.884 10.151.3.14 local1.info [S=2] sc |PstnTermReason|LatchedRtpIp |LatchedRtpPort |LatchedT38Ip |LatchedT38Port 09:53:55.885 10.151.3.14 local1.info [S=3] |CALL_END |0 |58c3aa:27:10 |1 |-1 |0 |0 |1 |FXS |RMT |10.151.3.14 |10.151.3.14 |0 |0 |05333096548 |05333096548 |0 |0 |1000 |1000 |0 |N/A |255 | |0 |RMT |RELEASE_BECAUSE_FORWARD |0 |-1 |-1 |0 |0 |394224111120100315@10.151.3.14 |00:03:15.660 UTC Fri Jan 01 2010 | |00:03:15.671 UTC Fri Jan 01 2010 |-1 |-1 |-1 |-1 |-1 |0 |0 | |0 |10.151.3.14 |10.151.3.14 |10.151.3.14 |10.151.3.14 |Caller Addr |10.151.3.14 |6020 | | |FORWARDED | |DefaultSRD |GW |Caller_IP | |DefaultRealm |UDP |46 |24 |127 |127 |127 |127 |5060 |5070 |AUDIO | | |302 | 09:53:55.889 10.151.3.14 local1.info [S=4] |0 | |0 | |0 09:53:55.889 10.151.3.14 local0.notice [S=677] [SID=58c3aa:27:10] ( lgr_flow)( 361) | | (#25):RELEASE_ACK_EV:(394224111120100315@10.151.3.14) 09:53:55.891 10.151.3.14 local0.notice [S=678] [SID=58c3aa:27:10] ( lgr_flow)( 362) | | (#25):Call will not free media resources since Call is forwarded 09:53:55.892 10.151.3.14 local0.notice [S=679] [SID=58c3aa:27:10] ( lgr_flow)( 363) | #0:RELEASE_EV RELEASE_BECAUSE_FORWARD : (394224111120100315@10.151.3.14) 09:53:55.893 10.151.3.14 local0.notice [S=680] [SID=58c3aa:27:10] ( lgr_flow)( 364) | | (#25):RELEASE_ACK_EV:(394224111120100315@10.151.3.14) 09:53:55.894 10.151.3.14 local0.notice [S=681] [SID=58c3aa:27:10] ( lgr_flow)( 365) | | | (#20)SIPStackSession <- (#0)ENDPOINT: RELEASE_ACK_EV (394224111120100315@10.151.3.14) 09:53:55.894 10.151.3.14 local0.notice [S=682] [SID=58c3aa:27:10] ( sip_stack)( 366) New SIPMessage created - (#103) 09:53:55.895 10.151.3.14 local0.notice [S=683] [SID=58c3aa:27:10] ( lgr_stk_mngr)( 367) SIPAppMngr::FreeCallAPI - (#27) 09:53:55.896 10.151.3.14 local0.notice [S=684] [SID=58c3aa:27:10] ( sip_stack)( 368) Setting ApplicationCall of AcSIPCall(#4665) to NULL 09:53:55.898 10.151.3.14 local0.notice [S=685] [SID=58c3aa:27:10] ( sip_stack)( 369) SDPBodyMedia::Delete - id = (#460) 09:53:55.898 10.151.3.14 local0.notice [S=686] [SID=58c3aa:27:10] ( sip_stack)( 370) Resource BufferSDPBody deleted - (#175) 09:53:55.899 10.151.3.14 local0.notice [S=687] [SID=58c3aa:27:10] ( lgr_stk_mngr)( 371) Resource StackSession (#20) Deleted 09:53:55.899 10.151.3.14 local0.notice [S=688] [SID=58c3aa:27:10] ( sip_stack)( 372) Resource SIPMessage deleted - (#103) 09:53:55.900 10.151.3.14 local0.notice [S=689] [SID=58c3aa:27:10] ( lgr_call)( 373) (#25) CALL Deallocated. 09:53:55.901 10.151.3.14 local0.notice [S=690] [SID=58c3aa:27:10] ( lgr_call)( 374) delete GW call current active is: 1 09:53:55.901 10.151.3.14 local0.notice [S=691] [SID=58c3aa:27:10] ( lgr_flow)( 375) EndPoint::MediaResourceList Perform FORCE Deallocation of port: Realm:1 port:0 current allocations:6020 09:53:55.906 10.151.3.14 local0.notice [S=692] [SID=58c3aa:27:10] ( lgr_psbrdif)( 376) #0:cpDigitMapHndlr_Stop - Stopped (0) 09:53:55.908 10.151.3.14 local0.notice [S=693] [SID=58c3aa:27:10] ( lgr_veint)( 377) #0:CloseChannel: ChannelNum=0 09:53:55.909 10.151.3.14 local0.notice [S=694] [SID=58c3aa:27:11] ( lgr_flow)( 378) (#5343) gwSession Allocated. Handle:12DA1928; Global session ID: 224ab5bb51699d74 09:53:55.909 10.151.3.14 local0.notice [S=695] [SID=58c3aa:27:11] ( lgr_veint)( 379) Open channel: IsVoiceOn: True, IsT38On: False, IsVbdOn: False 09:53:55.909 10.151.3.14 local0.notice [S=696] [SID=58c3aa:27:11] ( lgr_veint)( 380) #0:OpenChannel:on Trunk -1 BChannel:0 CID=0 with VoiceCoder: g711Alaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED 09:53:55.911 10.151.3.14 local0.notice [S=697] [SID=58c3aa:27:11] ( lgr_veint)( 381) #0:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 09:53:55.912 10.151.3.14 local0.notice [S=698] [SID=58c3aa:27:11] ( lgr_veint)( 382) RFC2833RTPPayloadType: Rx=96 Tx=96 09:53:55.912 10.151.3.14 local0.notice [S=699] [SID=58c3aa:27:11] ( lgr_veint)( 383) OpenChannel, CoderType = 0, Interval = 3, M = 1 09:53:55.918 10.151.3.14 local0.notice [S=700] [SID=58c3aa:27:11] ( lgr_veint)( 384) OpenChannel, RTPMode = 1, UniDirectionalRTP = 0 09:53:55.919 10.151.3.14 local0.notice [S=701] [SID=58c3aa:27:11] ( lgr_veint)( 385) ConfigureVbdAndT38:FAXTransportType 1VxxTransportType 0 T38Version:False FaxBypassPT:102 09:53:55.919 10.151.3.14 local0.notice [S=702] [SID=58c3aa:27:11] ( lgr_veint)( 386) #0:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=0, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, ECEType=0, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, DJBufMaxDelay=300, Result=1 09:53:55.920 10.151.3.14 local0.notice [S=703] [SID=58c3aa:27:11] ( lgr_veint)( 387) #0:FAXTransportType = 1 09:53:55.921 10.151.3.14 local0.notice [S=704] [SID=58c3aa:27:11] ( lgr_veint)( 388) SetDetectorsField :[ SIT] IPSide:[] SignalingMask=0xA1 09:53:55.922 10.151.3.14 local0.notice [S=705] [SID=58c3aa:27:11] ( lgr_veint)( 389) #0:Channel will be open WITH DSP 09:53:55.923 10.151.3.14 local0.notice [S=706] [SID=58c3aa:27:11] ( sip_stack)( 390) Resource SIPMessage deleted - (#105) 09:53:55.924 10.151.3.14 local0.notice [S=707] [SID=58c3aa:27:11] ( lgr_stk_ses)( 391) SIPBaseStackSession(#20)::ProcessQueuedEvents: StackSession is released. Stopping iteration. 09:53:55.924 10.151.3.14 local0.notice [S=708] [SID=58c3aa:27:11] ( sip_stack)( 392) SDPBody::Delete - id = (#52) 09:53:55.925 10.151.3.14 local0.notice [S=709] [SID=58c3aa:27:11] ( sip_stack)( 393) SDPBodyMedia::Delete - id = (#463) 09:53:55.926 10.151.3.14 local0.notice [S=710] [SID=58c3aa:27:11] ( sip_stack)( 394) Resource SIPMessage deleted - (#108) 09:53:55.928 10.151.3.14 local0.notice [S=711] [SID=58c3aa:27:10] ( lgr_flow)( 395) (#0)IDSMngr <- (#0): IDSReportEvent 09:53:55.929 10.151.3.14 local0.notice [S=712] [SID=58c3aa:27:9] ( sip_stack)( 396) ---- Incoming SIP Message from 10.151.3.14:5070 to SIPInterface #0 (SBC) UDP TO(#0) ---- 09:53:55.930 10.151.3.14 local0.notice [S=713] [SID=58c3aa:27:9] SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1808763847 From: "05333096548" ;tag=1c1622334087 To: Call-ID: 394224111120100315@10.151.3.14 CSeq: 1 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: M500/v.7.20A.154.052 Content-Length: 0 09:53:55.934 10.151.3.14 local0.notice [S=714] [SID=58c3aa:27:9] ( sip_stack)( 397) New SIPMessage created - (#106) 09:53:55.934 10.151.3.14 local0.notice [S=715] [SID=58c3aa:27:9] ( sip_stack)( 398) UdpRtxMngr::Remove 1 INVITE 09:53:55.935 10.151.3.14 local0.notice [S=716] [SID=58c3aa:27:9] ( lgr_flow)( 399) | |(SIPTU(#4666)100 State:Inviting(394224111120100315@10.151.3.14) 09:53:55.936 10.151.3.14 local0.notice [S=717] [SID=58c3aa:27:9] ( sip_stack)( 400) SIPCall(#4666) changes state from Inviting to Proceeding 09:53:55.936 10.151.3.14 local0.notice [S=718] [SID=58c3aa:27:9] ( sip_stack)( 401) Resource SIPMessage deleted - (#106) 09:53:55.938 10.151.3.14 local0.notice [S=719] [SID=58c3aa:27:1] ( lgr_flow)( 402) (#5353) gwSession Deallocated. 09:53:55.938 10.151.3.14 local0.notice [S=720] [SID=58c3aa:27:9] ( sip_stack)( 403) ---- Incoming SIP Message from 10.151.3.14:5070 to SIPInterface #0 (SBC) UDP TO(#0) ---- 09:53:55.939 10.151.3.14 local0.notice [S=721] [SID=58c3aa:27:9] SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1808763847 From: "05333096548" ;tag=1c1622334087 To: ;tag=1c904537791 Call-ID: 394224111120100315@10.151.3.14 CSeq: 1 INVITE Contact: Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Diversion: ;reason=unconditional;counter=1 Server: M500/v.7.20A.154.052 Reason: SIP ;cause=302 ;text="302 Moved Temporarily" Content-Length: 0 09:53:55.940 10.151.3.14 local0.notice [S=722] [SID=58c3aa:27:9] ( sip_stack)( 404) New SIPMessage created - (#104) 09:53:55.940 10.151.3.14 local0.notice [S=723] [SID=58c3aa:27:9] ( lgr_flow)( 405) | |(SIPTU(#4666)302 State:Proceeding(394224111120100315@10.151.3.14) 09:53:55.941 10.151.3.14 local0.notice [S=724] [SID=58c3aa:27:9] ( sip_stack)( 406) New SIPMessage created - (#102) 09:53:55.946 10.151.3.14 local0.notice [S=725] [SID=58c3aa:27:9] ( sip_stack)( 407) ---- Outgoing SIP Message to 10.151.3.14:5070 from SIPInterface #0 (SBC) UDP TO(#0) ---- 09:53:55.947 10.151.3.14 local0.notice [S=726] [SID=58c3aa:27:9] ACK sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1808763847 Max-Forwards: 70 From: "05333096548" ;tag=1c1622334087 To: ;tag=1c904537791 Call-ID: 394224111120100315@10.151.3.14 CSeq: 1 ACK Contact: User-Agent: M500/v.7.20A.154.052 Content-Length: 0 09:53:55.948 10.151.3.14 local0.notice [S=727] [SID=58c3aa:27:9] ( lgr_flow)( 408) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:53:55.949 10.151.3.14 local0.notice [S=728] [SID=58c3aa:27:9] ( sip_stack)( 409) Resource SIPMessage deleted - (#102) 09:53:55.949 10.151.3.14 local0.notice [S=729] [SID=58c3aa:27:9] ( lgr_flow)( 410) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:53:55.950 10.151.3.14 local0.notice [S=730] [SID=58c3aa:27:9] ( sip_stack)( 411) SIPCall(#4666) changes state from Proceeding to Disconnected 09:53:55.951 10.151.3.14 local0.notice [S=731] [SID=58c3aa:27:9] ( lgr_flow)( 412) | | | (#4058)SIPSBCCallLeg <- (#4058)AcSBCCallAPI: SBCDisconnectData 09:53:55.951 10.151.3.14 local0.notice [S=732] [SID=58c3aa:27:9] ( lgr_stk_ses)( 413) SESSION (#4058) SendToCall - event: RELEASE_EV m_Call(#26) 09:53:55.953 10.151.3.14 local0.notice [S=733] [SID=58c3aa:27:9] ( lgr_flow)( 414) | | (#26)SBCVoiceCall <- (#4058)SIPSBCCallLeg: RELEASE_EV 09:53:55.954 10.151.3.14 local0.notice [S=734] [SID=58c3aa:27:9] ( lgr_flow)( 415) | | (#26) SBCCall changing states from:InitiatedState to:DisconnectingState 09:53:55.955 10.151.3.14 local0.notice [S=735] [SID=58c3aa:27:9] ( lgr_flow)( 416) | (#129)SBCCallPlacementFeature <- (#26)SBCVoiceCall: RELEASE_EV 09:53:55.956 10.151.3.14 local0.notice [S=736] [SID=58c3aa:27:9] ( lgr_flow)( 417) (#119)SBCRoutesIterator::Change State From: AlternativeRouting To : Alternative3xx 09:53:55.956 10.151.3.14 local0.notice [S=737] [SID=58c3aa:27:9] ( lgr_flow)( 418) (#119)SBCRoutesIterator::Change State From: Alternative3xx To : InitialCSRRouting 09:53:55.960 10.151.3.14 local0.notice [S=738] [SID=58c3aa:27:9] ( lgr_flow)( 419) (#119)SBCRoutesIterator::Change State From: InitialCSRRouting To : InitialRouting 09:53:55.960 10.151.3.14 local0.notice [S=739] [SID=58c3aa:27:9] ( lgr_flow)( 420) (#119)SBCRoutesIterator::Change State From: InitialRouting To : AlternativeRouting 09:53:55.961 10.151.3.14 local0.notice [S=740] [SID=58c3aa:27:9] ( lgr_flow)( 421) (#-1)SBCAdmissionControlMngr <- (#119)SBCRoutesIterator: SBC_ADMIT_DIALOGS_EV 09:53:55.963 10.151.3.14 local0.notice [S=741] [SID=58c3aa:27:9] ( lgr_sbc)( 422) CallAdmission::AddDialog: Type INVITE LegType: SBC Direction Out IP group 0 (Caller Addr): 1 SRD 0 (DefaultSRD): 2 SRD ovflw:2 SipInterface 0 (SBC): 2 used unres:3 09:53:55.964 10.151.3.14 local0.notice [S=742] [SID=58c3aa:27:9] ( media_service)( 423) ServicesMngr: Allocate SBC leg. current active: 2 and max is: 10 09:53:55.965 10.151.3.14 local0.notice [S=743] [SID=58c3aa:27:9] ( lgr_flow)( 424) (#119)SBCRoutesIterator::Next route found: Rule #0, Route by: Address , IP Group ID: 0 (Caller Addr), Url:10.147.253.165:0; 09:53:55.966 10.151.3.14 local0.notice [S=744] [SID=58c3aa:27:9] ( lgr_sbc)( 425) Routing Succeeded -IP2IPRouting Rule #0 09:53:55.967 10.151.3.14 local0.notice [S=745] [SID=58c3aa:27:9] ( sip_stack)( 426) Setting ApplicationCall of AcSIPCall(#4666) to NULL 09:53:55.968 10.151.3.14 local0.notice [S=746] [SID=58c3aa:27:9] ( lgr_flow)( 427) | (#129)SBCCallPlacementFeature <- (#119)SBCRoutesIterator: SBC_ROUTING_DONE_EV 09:53:55.969 10.151.3.14 local0.notice [S=747] [SID=58c3aa:27:9] ( lgr_call)( 428) (#24) CALL Allocated. 09:53:55.970 10.151.3.14 local0.notice [S=748] [SID=58c3aa:27:9] ( lgr_flow)( 429) | | (#24)SBCVoiceCall <- (#129)SBCCallPlacementFeature: NEW_CALL_EV 09:53:55.970 10.151.3.14 local0.notice [S=749] [SID=58c3aa:27:9] ( lgr_flow)( 430) | | (#24) SBCCall changing states from:IdleState to:NewCallState 09:53:55.974 10.151.3.14 local0.notice [S=750] [SID=58c3aa:27:9] ( lgr_call)( 431) Profiled: JB=0 JBMinDel=10 JBOptF=10 JBMaxDel=300 IPDS=46 SigIPDF=24 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 RBTid=-1 HTid=-1 ExtCGrp=-1 TMod=0 AllMedia= AllCGrp=-1 AllVCGrp=-1 ACdrM=0 MSBeh=0 MSMet=0 RTCPF=0 R2833B=0 AltDM=0 MltDTMF=0 AssrtID=1 DivM=0 HisIM=1 FaxCGId=-1 FaxBeh=0 FaxOffM=0 FaxAnsM=1 FaxReroutingM=0 PrkMod=3 RmtUpdSup=2 RmtRvtSup=2 RmtDOS=1 RmtRB=0 Rmt3B=2 RmtM18xS=1 RmtEMRT=0 RmtEMS=1 RmtRBSR=0 RmtSR3960=0 RmtCPRB=1 RmtHF=0 SDPPTA=0 PrfPT=0 USS=0 2833PT=0 RmtRepB=0 RmtMulEr=-1 RmtMulAns=0 RTPRedB=0 PlRbTr=0 AdptRfcBwVoiceBw=0 RTCPAttr=0 RemCryptoLT=0 Ice=0 RTCPMux=0 HandleXDetect=0 DMTag= SBCMaxCD=0 SBCGenRTP=0 VQ=0 OpusMaxBW=0 09:53:55.975 10.151.3.14 local0.notice [S=751] [SID=58c3aa:27:9] ( lgr_stk_mngr)( 432) (#4057)SIPSBCCallLeg Allocated. 09:53:55.976 10.151.3.14 local0.notice [S=752] [SID=58c3aa:27:9] ( lgr_flow)( 433) | | | (#4057)SIPSBCCallLeg <- (#24)SBCVoiceCall: NEW_CALL_EV 09:53:55.978 10.151.3.14 local0.notice [S=753] [SID=58c3aa:27:9] ( lgr_flow)( 434) (#146) MediaMetaDataList Allocated. 09:53:55.978 10.151.3.14 local0.notice [S=754] [SID=58c3aa:27:9] ( lgr_flow)( 435) | | (#24)SBCVoiceCall <- (#129)SBCCallPlacementFeature: SETUP_EV 09:53:55.979 10.151.3.14 local0.notice [S=755] [SID=58c3aa:27:9] ( sip_stack)( 436) SDPBody::New - id = (#51) 09:53:55.979 10.151.3.14 local0.notice [S=756] [SID=58c3aa:27:9] ( sip_stack)( 437) SDPBodyMedia::New - id = (#459) 09:53:55.980 10.151.3.14 local0.notice [S=757] [SID=58c3aa:27:9] ( lgr_flow)( 438) | | (#24) SBCCall changing states from:NewCallState to:InitiatedState 09:53:55.981 10.151.3.14 local0.notice [S=758] [SID=58c3aa:27:9] ( lgr_flow)( 439) | | | (#4057)SIPSBCCallLeg <- (#24)SBCVoiceCall: SETUP_EV 09:53:55.983 10.151.3.14 local0.notice [S=759] [SID=58c3aa:27:9] ( lgr_stk_ses)( 440) SIPSBCCallLeg(#4057) - Route by Dest Address: 10.147.253.165 09:53:55.985 10.151.3.14 local0.notice [S=760] [SID=58c3aa:27:9] ( lgr_flow)( 441) | | new AcSIPCall created - (#4664) 09:53:55.985 10.151.3.14 local0.notice [S=761] [SID=58c3aa:27:9] ( lgr_stk_ses)( 442) SBCOfferAnswerMngr(#4057) changes state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERING 09:53:55.990 10.151.3.14 local0.notice [S=762] [SID=58c3aa:27:9] ( media_service)( 443) (#7) MediaResourceAdapter Allocated. OrigSide TEL 09:53:55.990 10.151.3.14 local0.notice [S=763] [SID=58c3aa:27:9] ( lgr_stk_ses)( 444) SBCSDPDTMFFeature::UpdateMediaDTMFConfig: m_RFC=0 m_Alternative=0 m_PeerRFC=0 m_PeerAlternative=0 m_SendMultipleDTMFMethods=False m_PeerSendMultipleDTMFMethods=False 09:53:55.990 10.151.3.14 local0.notice [S=764] [SID=58c3aa:27:9] ( lgr_stk_ses)( 445) SBCSDPANATFeature::UpdateANATConfig - Behavior isIP_VERSION_ONLY_IPV4 09:53:55.991 10.151.3.14 local0.notice [S=765] [SID=58c3aa:27:9] ( media_service)( 446) (#17) RTPStreamResource Allocated. Sibling ID: (#-1) 09:53:55.992 10.151.3.14 local0.notice [S=766] [SID=58c3aa:27:9] ( media_service)( 447) (#7) ChannelResource Allocated. 09:53:55.993 10.151.3.14 local0.notice [S=767] [SID=58c3aa:27:9] ( media_service)( 448) (#7) ChannelResource belongs to call #24 09:53:55.993 10.151.3.14 local0.notice [S=768] [SID=58c3aa:27:9] ( lgr_flow)( 449) (#7)ChannelResource::AllocateMediaIpPorts RealmIndex(0) port(6030) Allocated. 09:53:55.994 10.151.3.14 local0.notice [S=769] [SID=58c3aa:27:9] ( lgr_flow)( 450) #MediaResourcesConnector::AllocateMediaResources 09:53:55.995 10.151.3.14 local0.notice [S=770] [SID=58c3aa:27:9] ( media_connect)( 451) ConnectionData::CalculateResourcesForRTPForwarding Leading:NONE Opposite:NONE MediationLevel:RTP 09:53:55.996 10.151.3.14 local0.notice [S=771] [SID=58c3aa:27:9] ( lgr_flow)( 452) #MediaResourcesConnector::AllocateMediaResources 09:53:55.996 10.151.3.14 local0.notice [S=772] [SID=58c3aa:27:9] ( media_connect)( 453) ConnectionData::CalculateResourcesForRTPForwarding Leading:NONE Opposite:NONE MediationLevel:RTP 09:53:55.998 10.151.3.14 local0.notice [S=773] [SID=58c3aa:27:9] ( lgr_stk_ses)( 454) DirectMediaDB::StoreSDP added DirectMediaData to Database IP:10.151.3.14 Port:6030 SDP:(#178) 09:53:55.998 10.151.3.14 local0.notice [S=774] [SID=58c3aa:27:9] ( lgr_flow)( 455) (#145) MediaMetaDataList Allocated. 09:53:56.003 10.151.3.14 local0.notice [S=775] [SID=58c3aa:27:9] ( lgr_stk_mngr)( 456) New BufferSDPBody created - (#174) 09:53:56.004 10.151.3.14 local0.notice [S=776] [SID=58c3aa:27:9] ( sip_stack)( 457) SDPBodyMedia::New - id = (#458) 09:53:56.004 10.151.3.14 local0.notice [S=777] [SID=58c3aa:27:9] ( sip_sbc)( 458) SIPSBCMngr::GetStackEP - No user is found in DB 09:53:56.005 10.151.3.14 local0.notice [S=778] [SID=58c3aa:27:9] ( lgr_flow)( 459) | |(SIPTU(#4664)SETUP_REQ State:Idle() 09:53:56.006 10.151.3.14 local0.notice [S=779] [SID=58c3aa:27:9] ( sip_stack)( 460) SIPCall(#4664) changes state from Idle to Inviting 09:53:56.008 10.151.3.14 local0.notice [S=780] [SID=58c3aa:27:9] ( sip_stack)( 461) TU(#4664) SIPSessionTimer::FillSTRequestData - Session-Timer mode: TRANSPARENT 09:53:56.009 10.151.3.14 local0.notice [S=781] [SID=58c3aa:27:9] ( lgr_stack)( 462) [ManSet: 1, Rule: 2] - Executed on msgId:110 (Condition was True) 09:53:56.010 10.151.3.14 local0.notice [S=782] [SID=58c3aa:27:9] ( sip_stack)( 463) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 6 Dest: 10.147.253.165:5060 CallID: (394224111120100315@10.151.3.14) 09:53:56.010 10.151.3.14 local0.notice [S=783] [SID=58c3aa:27:9] ( sip_stack)( 464) ---- Outgoing SIP Message to 10.147.253.165:5060 from SIPInterface #0 (SBC) UDP TO(#0) ---- 09:53:56.010 10.151.3.14 local0.notice [S=784] [SID=58c3aa:27:9] INVITE sip:05352038980@10.147.253.165;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac212361337 Max-Forwards: 69 From: "05333096548" ;tag=1c1169164280 To: Call-ID: 394224111120100315@10.151.3.14 CSeq: 1 INVITE Contact: Supported: replaces,sdp-anat Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE User-Agent: M500/v.7.20A.154.052 P-Asserted-Identity: History-Info: ;index=1 History-Info: ;index=1.1 Content-Type: application/sdp Content-Length: 209 v=0 o=- 425210444 1156234764 IN IP4 10.151.3.14 s=X-Lite release 5.0.3 stamp 88254 c=IN IP4 10.151.3.14 t=0 0 m=audio 6030 RTP/AVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv 09:53:56.011 10.151.3.14 local0.notice [S=785] [SID=58c3aa:27:9] ( lgr_flow)( 465) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:53:56.017 10.151.3.14 local0.notice [S=786] [SID=58c3aa:27:9] ( lgr_flow)( 466) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:53:56.018 10.151.3.14 local0.notice [S=787] [SID=58c3aa:27:9] ( sip_stack)( 467) SDPBody::Delete - id = (#51) 09:53:56.019 10.151.3.14 local0.notice [S=788] [SID=58c3aa:27:9] ( sip_stack)( 468) SDPBodyMedia::Delete - id = (#459) 09:53:56.019 10.151.3.14 local0.notice [S=789] [SID=58c3aa:27:9] ( lgr_flow)( 469) | | (#26)SBCVoiceCall <- (#129)SBCCallPlacementFeature: RELEASE_ACK_EV 09:53:56.020 10.151.3.14 local0.notice [S=790] [SID=58c3aa:27:9] ( lgr_flow)( 470) | | (#26) SBCCall changing states from:DisconnectingState to:DisconnectedState 09:53:56.021 10.151.3.14 local0.notice [S=791] [SID=58c3aa:27:9] ( lgr_flow)( 471) | | | (#4058)SIPSBCCallLeg <- (#26)SBCVoiceCall: RELEASE_ACK_EV 09:53:56.023 10.151.3.14 local0.notice [S=792] [SID=58c3aa:27:9] ( sip_stack)( 472) New SIPMessage created - (#100) 09:53:56.023 10.151.3.14 local0.notice [S=793] [SID=58c3aa:27:9] ( sip_stack)( 473) AcSBCCallAPI::DisconnectAck: No SIPCall 09:53:56.024 10.151.3.14 local0.notice [S=794] [SID=58c3aa:27:9] ( lgr_stk_ses)( 474) SIPSBCCallLeg(#4058) Deallocated 09:53:56.025 10.151.3.14 local0.notice [S=795] [SID=58c3aa:27:9] ( sip_stack)( 475) SDPBodyMedia::Delete - id = (#464) 09:53:56.026 10.151.3.14 local0.notice [S=796] [SID=58c3aa:27:9] ( sip_stack)( 476) Resource BufferSDPBody deleted - (#177) 09:53:56.027 10.151.3.14 local0.notice [S=797] [SID=58c3aa:27:9] ( lgr_flow)( 477) (#147) MediaMetaDataList Deallocated. 09:53:56.031 10.151.3.14 local0.notice [S=798] [SID=58c3aa:27:9] ( lgr_stk_ses)( 478) DirectMediaDB::RemoveData Removed DirectMediaData from Database IP:10.151.3.14 Port:6010 SDP:(#178) 09:53:56.033 10.151.3.14 local0.notice [S=799] [SID=58c3aa:27:9] ( sip_stack)( 479) Resource SIPMessage deleted - (#100) 09:53:56.033 10.151.3.14 local1.info [S=5] |SBCReportType |EPTyp |SIPCallId |SessionId |Orig |SourceIp |SourcePort |DestIp |DestPort |TransportType |SrcURI |SrcURIBeforeMap |DstURI |DstURIBeforeMap |Durat|TrmSd|TrmReason |TrmReasonCategory|SetupTime |ConnectTime |ReleaseTime |RedirectReason |RedirectURINum |RedirectURINumBeforeMap |TxSigIPDiffServ|IPGroup (name) |SrdId (name) |SIPInterfaceId (name) |ProxySetId (name) |IpProfileId (name) |MediaRealmId (name) |DirectMedia|SIPTrmReason|SIPTermDesc |Caller |Callee |Trigger |LegId 09:53:56.036 10.151.3.14 local1.info [S=6] |CALL_END |SBC |394224111120100315@10.151.3.14 |58c3aa:27:9 |LCL |10.151.3.14 |5060 |10.151.3.14 |5070 |UDP |05333096548@10.151.3.14 |05333096548@10.151.3.14 |1000@10.151.3.14 |1000@10.151.3.14 |0 |RMT |RELEASE_BECAUSE_FORWARD |FORWARDED |00:03:15.600 UTC Fri Jan 01 2010 | |00:03:15.756 UTC Fri Jan 01 2010 |-1 | | |24 |GW |DefaultSRD |SBC |GW_IP |Emre |DefaultRealm |no |302 |SIP ;cause=302 ;text="302 |05333096548 | |Normal |2 09:53:56.036 10.151.3.14 local0.notice [S=800] [SID=58c3aa:27:9] ( media_service)( 480) (#8) MediaResourceAdapter Deallocated. 09:53:56.036 10.151.3.14 local0.notice [S=801] [SID=58c3aa:27:9] ( lgr_flow)( 481) (#18)RTPStreamResource::DeAllocateResource, CID -100 Detached. 09:53:56.039 10.151.3.14 local0.notice [S=802] [SID=58c3aa:27:9] ( lgr_flow)( 482) (#8)ChannelResource::DeAllocateMediaIpPorts RealmIndex(0) port(6010) IPV4IF(0) Deallocated. 09:53:56.039 10.151.3.14 local0.notice [S=803] [SID=58c3aa:27:9] ( media_service)( 483) (#8) ChannelResource Deallocated. 09:53:56.039 10.151.3.14 local0.notice [S=804] [SID=58c3aa:27:9] ( media_service)( 484) (#18) RTPStreamResource Deallocated. 09:53:56.040 10.151.3.14 local0.notice [S=805] [SID=58c3aa:27:9] ( lgr_gwapp_ha)( 485) (#26)SBCVoiceCall Insert Delete Resource event 09:53:56.044 10.151.3.14 local0.notice [S=806] [SID=58c3aa:27:10] ( sip_stack)( 486) ---- Incoming SIP Message from 10.151.3.14:5060 to SIPInterface #1 (GW) UDP TO(#1) ---- 09:53:56.045 10.151.3.14 local0.notice [S=807] [SID=58c3aa:27:10] ACK sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1808763847 Max-Forwards: 70 From: "05333096548" ;tag=1c1622334087 To: ;tag=1c904537791 Call-ID: 394224111120100315@10.151.3.14 CSeq: 1 ACK Contact: User-Agent: M500/v.7.20A.154.052 Content-Length: 0 09:53:56.045 10.151.3.14 local0.notice [S=808] [SID=58c3aa:27:10] ( sip_stack)( 487) New SIPMessage created - (#101) 09:53:56.049 10.151.3.14 local0.notice [S=809] [SID=58c3aa:27:10] ( sip_stack)( 488) UdpRtxMngr::Remove 302 Response 1 INVITE 09:53:56.049 10.151.3.14 local0.notice [S=810] [SID=58c3aa:27:10] ( lgr_flow)( 489) | |(SIPTU(#4665)ACK State:Disconnected(394224111120100315@10.151.3.14) 09:53:56.049 10.151.3.14 local0.notice [S=811] [SID=58c3aa:27:10] ( sip_stack)( 490) Resource SIPMessage deleted - (#101) 09:53:56.051 10.151.3.14 local0.notice [S=812] [SID=58c3aa:27:9] ( lgr_sbc)( 491) CallAdmission::Remove: Type INVITE LegType: SBC Direction Out IP group 1 (GW): 0 SRD 0 (DefaultSRD) SRD ovflw:1 SipInterface 0 (SBC): 1 used unres:2 09:53:56.051 10.151.3.14 local0.notice [S=813] [SID=58c3aa:27:9] ( media_service)( 492) ServicesMngr: Deallocate SBC leg. current active: 3 and max is: 10 09:53:56.051 10.151.3.14 local0.notice [S=814] [SID=58c3aa:27:9] ( lgr_call)( 493) (#26) CALL Deallocated. 09:53:56.163 10.151.3.14 local0.notice [S=815] [SID=58c3aa:27:9] ( sip_stack)( 494) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 5 Dest: 10.147.253.165:5060 CallID: (394224111120100315@10.151.3.14) 09:53:56.164 10.151.3.14 local0.notice [S=816] [SID=58c3aa:27:9] ( sip_stack)( 495) SIPTransaction(#986)::SendMsgBuffer - Resending last message 09:53:56.164 10.151.3.14 local0.notice [S=817] [SID=58c3aa:27:9] ( lgr_flow)( 496) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:53:56.170 10.151.3.14 local0.notice [S=818] [SID=58c3aa:27:9] ( lgr_flow)( 497) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:53:57.154 10.151.3.14 local0.notice [S=819] [SID=58c3aa:27:9] ( sip_stack)( 498) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 4 Dest: 10.147.253.165:5060 CallID: (394224111120100315@10.151.3.14) 09:53:57.155 10.151.3.14 local0.notice [S=820] [SID=58c3aa:27:9] ( sip_stack)( 499) SIPTransaction(#986)::SendMsgBuffer - Resending last message 09:53:57.156 10.151.3.14 local0.notice [S=821] [SID=58c3aa:27:9] ( lgr_flow)( 500) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:53:57.156 10.151.3.14 local0.notice [S=822] [SID=58c3aa:27:9] ( lgr_flow)( 501) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:53:59.144 10.151.3.14 local0.notice [S=823] [SID=58c3aa:27:9] ( sip_stack)( 502) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 3 Dest: 10.147.253.165:5060 CallID: (394224111120100315@10.151.3.14) 09:53:59.145 10.151.3.14 local0.notice [S=824] [SID=58c3aa:27:9] ( sip_stack)( 503) SIPTransaction(#986)::SendMsgBuffer - Resending last message 09:53:59.146 10.151.3.14 local0.notice [S=825] [SID=58c3aa:27:9] ( lgr_flow)( 504) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:53:59.146 10.151.3.14 local0.notice [S=826] [SID=58c3aa:27:9] ( lgr_flow)( 505) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:54:00.616 10.151.3.14 local0.notice [S=827] [SID=58c3aa:27:9] ( sip_stack)( 506) ---- Incoming SIP Message from 10.151.3.13:5060 to SIPInterface #0 (SBC) UDP TO(#0) ---- 09:54:00.618 10.151.3.14 local0.notice [S=828] [SID=58c3aa:27:9] CANCEL sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.13:5060;branch=z9hG4bK-524287-1---4599362ea8638557;rport Max-Forwards: 70 To: From: "05333096548";tag=f11de856 Call-ID: 88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk CSeq: 1 CANCEL User-Agent: X-Lite release 5.0.3 stamp 88254 Content-Length: 0 09:54:00.619 10.151.3.14 local0.notice [S=829] [SID=58c3aa:27:9] ( sip_stack)( 507) New SIPMessage created - (#99) 09:54:00.619 10.151.3.14 local0.notice [S=830] [SID=58c3aa:27:9] ( lgr_flow)( 508) | |(SIPTU(#4667)CANCEL State:Invited(88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk) 09:54:00.620 10.151.3.14 local0.notice [S=831] [SID=58c3aa:27:9] ( sip_stack)( 509) SIPCall(#4667) changes state from Invited to Disconnected 09:54:00.621 10.151.3.14 local0.notice [S=832] [SID=58c3aa:27:9] ( sip_stack)( 510) New SIPMessage created - (#98) 09:54:00.622 10.151.3.14 local0.notice [S=833] [SID=58c3aa:27:9] ( sip_stack)( 511) ---- Outgoing SIP Message to 10.151.3.13:5060 from SIPInterface #0 (SBC) UDP TO(#0) ---- 09:54:00.623 10.151.3.14 local0.notice [S=834] [SID=58c3aa:27:9] SIP/2.0 200 OK Via: SIP/2.0/UDP 10.151.3.13:5060;received=10.151.3.13;rport=5060;branch=z9hG4bK-524287-1---4599362ea8638557 From: "05333096548" ;tag=f11de856 To: ;tag=1c1656691180 Call-ID: 88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk CSeq: 1 CANCEL Contact: Server: M500/v.7.20A.154.052 Content-Length: 0 09:54:00.624 10.151.3.14 local0.notice [S=835] [SID=58c3aa:27:9] ( lgr_flow)( 512) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:54:00.624 10.151.3.14 local0.notice [S=836] [SID=58c3aa:27:9] ( sip_stack)( 513) Resource SIPMessage deleted - (#98) 09:54:00.625 10.151.3.14 local0.notice [S=837] [SID=58c3aa:27:9] ( lgr_flow)( 514) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:54:00.626 10.151.3.14 local0.notice [S=838] [SID=58c3aa:27:9] ( sip_stack)( 515) New SIPMessage created - (#97) 09:54:00.628 10.151.3.14 local0.notice [S=839] [SID=58c3aa:27:9] ( sip_stack)( 516) UdpRtxMngr::Transmit 487 Response 1 INVITE Rtx Left: 6 Dest: 10.151.3.13:5060 CallID: (88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk) 09:54:00.629 10.151.3.14 local0.notice [S=840] [SID=58c3aa:27:9] ( sip_stack)( 517) ---- Outgoing SIP Message to 10.151.3.13:5060 from SIPInterface #0 (SBC) UDP TO(#0) ---- 09:54:00.629 10.151.3.14 local0.notice [S=841] [SID=58c3aa:27:9] SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.151.3.13:5060;received=10.151.3.13;rport=5060;branch=z9hG4bK-524287-1---4599362ea8638557 From: "05333096548" ;tag=f11de856 To: ;tag=1c1656691180 Call-ID: 88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk CSeq: 1 INVITE Server: M500/v.7.20A.154.052 Reason: SIP ;cause=487 ;text="487 Request Terminated" Content-Length: 0 09:54:00.630 10.151.3.14 local0.notice [S=842] [SID=58c3aa:27:9] ( lgr_flow)( 518) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 09:54:00.631 10.151.3.14 local0.notice [S=843] [SID=58c3aa:27:9] ( sip_stack)( 519) Resource SIPMessage deleted - (#97) 09:54:00.633 10.151.3.14 local0.notice [S=844] [SID=58c3aa:27:9] ( lgr_flow)( 520) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 09:54:00.633 10.151.3.14 local0.notice [S=845] [SID=58c3aa:27:9] ( lgr_flow)( 521) | | | (#4059)SIPSBCCallLeg <- (#4059)AcSBCCallAPI: SBCDisconnectData 09:54:00.634 10.151.3.14 local0.notice [S=846] [SID=58c3aa:27:9] ( lgr_stk_ses)( 522) SESSION (#4059) SendToCall - event: RELEASE_EV m_Call(#27) 09:54:00.635 10.151.3.14 local0.notice [S=847] [SID=58c3aa:27:9] ( lgr_flow)( 523) | | (#27)SBCVoiceCall <- (#4059)SIPSBCCallLeg: RELEASE_EV 09:54:00.636 10.151.3.14 local0.notice [S=848] [SID=58c3aa:27:9] ( lgr_flow)( 524) | | (#27) SBCCall changing states from:InitiatedState to:DisconnectingState 09:54:00.642 10.151.3.14 local0.notice [S=849] [SID=58c3aa:27:9] ( lgr_flow)( 525) (#4058)SBCParticipantEndPoint <- (#27)SBCVoiceCall: RELEASE_EV 09:54:00.644 10.151.3.14 local0.notice [S=850] [SID=58c3aa:27:9] ( lgr_flow)( 526) (#4058) SBCParticipantEndPoint changing states from:InitiatedState to:ReleaseingState 09:54:00.644 10.151.3.14 local0.notice [S=851] [SID=58c3aa:27:9] ( lgr_flow)( 527) (#2038)SBCController <- (#4058)SBCParticipantEndPoint: RELEASE_EV 09:54:00.645 10.151.3.14 local0.notice [S=852] [SID=58c3aa:27:9] ( lgr_flow)( 528) | | (#2038) SBCController changing states from:EstablishingState to:DisconnectingState 09:54:00.646 10.151.3.14 local0.notice [S=853] [SID=58c3aa:27:9] ( lgr_flow)( 529) (#4059)SBCParticipantEndPoint <- (#2038)SBCController: RELEASE_EV 09:54:00.646 10.151.3.14 local0.notice [S=854] [SID=58c3aa:27:9] ( lgr_flow)( 530) (#4059) SBCParticipantEndPoint changing states from:InitiatedState to:ReleaseingState 09:54:00.648 10.151.3.14 local0.notice [S=855] [SID=58c3aa:27:9] ( lgr_flow)( 531) | (#129)SBCCallPlacementFeature <- (#4059)SBCParticipantEndPoint: RELEASE_EV 09:54:00.649 10.151.3.14 local0.notice [S=856] [SID=58c3aa:27:9] ( lgr_flow)( 532) (#129) SBCCallPlacementFeature changing states from: Initiated to: Releasing 09:54:00.649 10.151.3.14 local0.notice [S=857] [SID=58c3aa:27:9] ( lgr_flow)( 533) | | (#24)SBCVoiceCall <- (#129)SBCCallPlacementFeature: RELEASE_EV 09:54:00.650 10.151.3.14 local0.notice [S=858] [SID=58c3aa:27:9] ( lgr_flow)( 534) | | (#24) SBCCall changing states from:InitiatedState to:DisconnectingState 09:54:00.651 10.151.3.14 local0.notice [S=859] [SID=58c3aa:27:9] ( lgr_flow)( 535) | | | (#4057)SIPSBCCallLeg <- (#24)SBCVoiceCall: RELEASE_EV 09:54:00.651 10.151.3.14 local0.notice [S=860] [SID=58c3aa:27:9] ( lgr_flow)( 536) | |(SIPTU(#4664)DISCONNECT_REQ State:Inviting(394224111120100315@10.151.3.14) 09:54:00.660 10.151.3.14 local0.notice [S=861] [SID=58c3aa:27:9] ( sip_stack)( 537) SIPCall(#4664) changes state from Inviting to Cancelling 09:54:00.660 10.151.3.14 local0.notice [S=862] [SID=58c3aa:27:9] ( sip_stack)( 538) UdpRtxMngr::Remove 1 INVITE 09:54:00.661 10.151.3.14 local0.notice [S=863] [SID=58c3aa:27:9] ( sip_stack)( 539) Resource SIPMessage deleted - (#99) 09:54:00.666 10.151.3.14 local0.notice [S=864] [SID=58c3aa:27:9] ( lgr_flow)( 540) (#0)IDSMngr <- (#0): IDSReportEvent 09:54:00.670 10.151.3.14 local0.notice [S=865] [SID=58c3aa:27:9] ( lgr_flow)( 541) | | | (#4057)SIPSBCCallLeg <- (#4057)AcSBCCallAPI: SBCDisconnectAckData 09:54:00.671 10.151.3.14 local0.notice [S=866] [SID=58c3aa:27:9] ( lgr_stk_ses)( 542) SESSION (#4057) SendToCall - event: RELEASE_ACK_EV m_Call(#24) 09:54:00.673 10.151.3.14 local0.notice [S=867] [SID=58c3aa:27:9] ( lgr_flow)( 543) | | (#24)SBCVoiceCall <- (#4057)SIPSBCCallLeg: RELEASE_ACK_EV 09:54:00.673 10.151.3.14 local0.notice [S=868] [SID=58c3aa:27:9] ( lgr_flow)( 544) | | (#24) SBCCall changing states from:DisconnectingState to:DisconnectedState 09:54:00.674 10.151.3.14 local0.notice [S=869] [SID=58c3aa:27:9] ( lgr_flow)( 545) | (#129)SBCCallPlacementFeature <- (#24)SBCVoiceCall: RELEASE_ACK_EV 09:54:00.675 10.151.3.14 local0.notice [S=870] [SID=58c3aa:27:9] ( lgr_flow)( 546) (#4059)SBCParticipantEndPoint <- (#129)SBCCallPlacementFeature: RELEASE_ACK_EV 09:54:00.675 10.151.3.14 local0.notice [S=871] [SID=58c3aa:27:9] ( lgr_flow)( 547) (#4059) SBCParticipantEndPoint changing states from:ReleaseingState to:ReleasedState 09:54:00.676 10.151.3.14 local0.notice [S=872] [SID=58c3aa:27:9] ( lgr_gwapp_ha)( 548) (#4059)SBCParticipantEndPoint Insert Delete Resource event 09:54:00.678 10.151.3.14 local0.notice [S=873] [SID=58c3aa:27:9] ( lgr_flow)( 549) (#2038)SBCController <- (#4059)SBCParticipantEndPoint: RELEASE_ACK_EV 09:54:00.679 10.151.3.14 local0.notice [S=874] [SID=58c3aa:27:9] ( lgr_flow)( 550) | | (#2038) SBCController changing states from:DisconnectingState to:DisconnectedState 09:54:00.679 10.151.3.14 local0.notice [S=875] [SID=58c3aa:27:9] ( media_connect)( 551) (#4) MediaResourcesConnector Deallocated. 09:54:00.680 10.151.3.14 local0.notice [S=876] [SID=58c3aa:27:9] ( lgr_gwapp_ha)( 552) (#2038)SBCController Insert Delete Resource event 09:54:00.681 10.151.3.14 local0.notice [S=877] [SID=58c3aa:27:9] ( lgr_flow)( 553) (#4058)SBCParticipantEndPoint <- (#2038)SBCController: RELEASE_ACK_EV 09:54:00.681 10.151.3.14 local0.notice [S=878] [SID=58c3aa:27:9] ( lgr_flow)( 554) (#4058) SBCParticipantEndPoint changing states from:ReleaseingState to:ReleasedState 09:54:00.683 10.151.3.14 local0.notice [S=879] [SID=58c3aa:27:9] ( lgr_gwapp_ha)( 555) (#4058)SBCParticipantEndPoint Insert Delete Resource event 09:54:00.684 10.151.3.14 local0.notice [S=880] [SID=58c3aa:27:9] ( lgr_flow)( 556) | | (#27)SBCVoiceCall <- (#4058)SBCParticipantEndPoint: RELEASE_ACK_EV 09:54:00.685 10.151.3.14 local0.notice [S=881] [SID=58c3aa:27:9] ( lgr_flow)( 557) | | (#27) SBCCall changing states from:DisconnectingState to:DisconnectedState 09:54:00.686 10.151.3.14 local0.notice [S=882] [SID=58c3aa:27:9] ( lgr_flow)( 558) | | | (#4059)SIPSBCCallLeg <- (#27)SBCVoiceCall: RELEASE_ACK_EV 09:54:00.686 10.151.3.14 local0.notice [S=883] [SID=58c3aa:27:9] ( sip_stack)( 559) New SIPMessage created - (#96) 09:54:00.688 10.151.3.14 local0.notice [S=884] [SID=58c3aa:27:9] ( lgr_flow)( 560) | |(SIPTU(#4667)DISCONNECT_RESPONSE State:Disconnected(88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk) 09:54:00.689 10.151.3.14 local0.notice [S=885] [SID=58c3aa:27:9] ( lgr_stk_ses)( 561) SIPSBCCallLeg(#4059) Deallocated 09:54:00.689 10.151.3.14 local0.notice [S=886] [SID=58c3aa:27:9] ( sip_stack)( 562) Setting ApplicationCall of AcSIPCall(#4667) to NULL 09:54:00.690 10.151.3.14 local0.notice [S=887] [SID=58c3aa:27:9] ( lgr_flow)( 563) (#149) MediaMetaDataList Deallocated. 09:54:00.694 10.151.3.14 local0.notice [S=888] [SID=58c3aa:27:9] ( sip_stack)( 564) Resource SIPMessage deleted - (#96) 09:54:00.694 10.151.3.14 local1.info [S=7] |CALL_END |SBC |88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk |58c3aa:27:9 |RMT |10.151.3.13 |5060 |10.151.3.14 |5060 |UDP |05333096548@10.151.3.14 |05333096548@10.151.3.14 |1000@10.151.3.14 |1000@10.151.3.14 |0 |RMT |GWAPP_NORMAL_CALL_CLEAR |NO_ANSWER |00:03:15.591 UTC Fri Jan 01 2010 | |00:03:20.697 UTC Fri Jan 01 2010 |-1 | | |24 |Caller Addr |DefaultSRD |SBC |Caller_IP |Emre |DefaultRealm |no |CANCEL | |05333096548 | |Normal |1 09:54:00.698 10.151.3.14 local0.notice [S=889] [SID=58c3aa:27:9] ( media_service)( 565) (#9) MediaResourceAdapter Deallocated. 09:54:00.699 10.151.3.14 local0.notice [S=890] [SID=58c3aa:27:9] ( lgr_flow)( 566) (#19)RTPStreamResource::DeAllocateResource, CID -100 Detached. 09:54:00.700 10.151.3.14 local0.notice [S=891] [SID=58c3aa:27:9] ( lgr_flow)( 567) (#9)ChannelResource::DeAllocateMediaIpPorts RealmIndex(0) port(6000) IPV4IF(0) Deallocated. 09:54:00.701 10.151.3.14 local0.notice [S=892] [SID=58c3aa:27:9] ( media_service)( 568) (#9) ChannelResource Deallocated. 09:54:00.701 10.151.3.14 local0.notice [S=893] [SID=58c3aa:27:9] ( media_service)( 569) (#19) RTPStreamResource Deallocated. 09:54:00.703 10.151.3.14 local0.notice [S=894] [SID=58c3aa:27:9] ( lgr_gwapp_ha)( 570) (#27)SBCVoiceCall Insert Delete Resource event 09:54:00.704 10.151.3.14 local0.notice [S=895] [SID=58c3aa:27:9] ( lgr_flow)( 571) (#129) SBCCallPlacementFeature changing states from: Releasing to: EndState 09:54:00.704 10.151.3.14 local0.notice [S=896] [SID=58c3aa:27:9] ( lgr_gwapp_ha)( 572) (#129)SBCCallPlacementFeature Insert Delete Resource event 09:54:00.705 10.151.3.14 local1.info [S=8] |CALL_END |SBC |394224111120100315@10.151.3.14 |58c3aa:27:9 |LCL |10.151.3.14 |5060 |10.147.253.165 |5060 |UDP |05333096548@10.151.3.14 |05333096548@10.151.3.14 |05352038980@10.147.253.165 |05352038980@10.147.253.165 |0 |LCL |GWAPP_NORMAL_CALL_CLEAR |NO_ANSWER |00:03:15.726 UTC Fri Jan 01 2010 | |00:03:20.701 UTC Fri Jan 01 2010 |15 |05352038980@10.147.253.165 |05352038980@10.147.253.165 |24 |Caller Addr |DefaultSRD |SBC |Caller_IP |Emre |DefaultRealm |no |CANCEL | |05333096548 | |Forward |3 09:54:00.706 10.151.3.14 local0.notice [S=897] [SID=58c3aa:27:9] ( media_service)( 573) (#7) MediaResourceAdapter Deallocated. 09:54:00.707 10.151.3.14 local0.notice [S=898] [SID=58c3aa:27:9] ( lgr_flow)( 574) (#17)RTPStreamResource::DeAllocateResource, CID -100 Detached. 09:54:00.708 10.151.3.14 local0.notice [S=899] [SID=58c3aa:27:9] ( lgr_flow)( 575) (#7)ChannelResource::DeAllocateMediaIpPorts RealmIndex(0) port(6030) IPV4IF(0) Deallocated. 09:54:00.709 10.151.3.14 local0.notice [S=900] [SID=58c3aa:27:9] ( media_service)( 576) (#7) ChannelResource Deallocated. 09:54:00.710 10.151.3.14 local0.notice [S=901] [SID=58c3aa:27:9] ( media_service)( 577) (#17) RTPStreamResource Deallocated. 09:54:00.711 10.151.3.14 local0.notice [S=902] [SID=58c3aa:27:9] ( lgr_gwapp_ha)( 578) (#24)SBCVoiceCall Insert Delete Resource event 09:54:00.711 10.151.3.14 local0.notice [S=903] [SID=58c3aa:27:9] ( lgr_stk_ses)( 579) SIPSBCCallLeg(#4057) Deallocated 09:54:00.712 10.151.3.14 local0.notice [S=904] [SID=58c3aa:27:9] ( sip_stack)( 580) Setting ApplicationCall of AcSIPCall(#4664) to NULL 09:54:00.714 10.151.3.14 local0.notice [S=905] [SID=58c3aa:27:9] ( sip_stack)( 581) SDPBodyMedia::Delete - id = (#458) 09:54:00.715 10.151.3.14 local0.notice [S=906] [SID=58c3aa:27:9] ( sip_stack)( 582) Resource BufferSDPBody deleted - (#174) 09:54:00.716 10.151.3.14 local0.notice [S=907] [SID=58c3aa:27:9] ( lgr_flow)( 583) (#145) MediaMetaDataList Deallocated. 09:54:00.717 10.151.3.14 local0.notice [S=908] [SID=58c3aa:27:9] ( lgr_stk_ses)( 584) DirectMediaDB::RemoveData Removed DirectMediaData from Database IP:10.151.3.14 Port:6030 SDP:(#178) 09:54:00.720 10.151.3.14 local0.notice [S=909] [SID=58c3aa:27:9] ( sip_stack)( 585) SDPBodyMedia::Delete - id = (#466) 09:54:00.721 10.151.3.14 local0.notice [S=910] [SID=58c3aa:27:9] ( sip_stack)( 586) Resource BufferSDPBody deleted - (#178) 09:54:00.721 10.151.3.14 local0.notice [S=911] [SID=58c3aa:27:9] ( lgr_stk_ses)( 587) SIPBaseStackSession(#4057)::ProcessQueuedEvents: StackSession is released. Stopping iteration. 09:54:00.728 10.151.3.14 local0.notice [S=912] [SID=58c3aa:27:9] ( sip_stack)( 588) SIPCall(#4666) changes state from Disconnected to Idle 09:54:00.728 10.151.3.14 local0.notice [S=913] [SID=58c3aa:27:9] ( lgr_flow)( 589) | | ACSIPStack::ReturnTU - (#4666) 09:54:00.729 10.151.3.14 local0.notice [S=914] [SID=58c3aa:27:9] ( media_service)( 590) (#2038) CONTROLLER Deallocated. 09:54:00.730 10.151.3.14 local0.notice [S=915] [SID=58c3aa:27:9] ( lgr_sbc)( 591) CallAdmission::Remove: Type INVITE LegType: SBC Direction In IP group 0 (Caller Addr): 0 SRD 0 (DefaultSRD) SRD ovflw:0 SipInterface 0 (SBC): 0 used unres:1 09:54:00.730 10.151.3.14 local0.notice [S=916] [SID=58c3aa:27:9] ( media_service)( 592) ServicesMngr: Deallocate SBC leg. current active: 2 and max is: 10 09:54:00.731 10.151.3.14 local0.notice [S=917] [SID=58c3aa:27:9] ( lgr_call)( 593) (#27) CALL Deallocated. 09:54:00.732 10.151.3.14 local0.notice [S=918] [SID=58c3aa:27:9] ( lgr_flow)( 594) (#148) MediaMetaDataList Deallocated. 09:54:00.733 10.151.3.14 local0.notice [S=919] [SID=58c3aa:27:9] ( sip_stack)( 595) Resource SIPMessage deleted - (#104) 09:54:00.734 10.151.3.14 local0.notice [S=920] [SID=58c3aa:27:9] ( sip_stack)( 596) SDPBody::Delete - id = (#54) 09:54:00.734 10.151.3.14 local0.notice [S=921] [SID=58c3aa:27:9] ( sip_stack)( 597) SDPBodyMedia::Delete - id = (#467) 09:54:00.735 10.151.3.14 local0.notice [S=922] [SID=58c3aa:27:9] ( sip_stack)( 598) Resource SIPMessage deleted - (#110) 09:54:00.736 10.151.3.14 local0.notice [S=923] [SID=58c3aa:27:9] ( lgr_flow)( 599) (#146) MediaMetaDataList Deallocated. 09:54:00.737 10.151.3.14 local0.notice [S=924] [SID=58c3aa:27:9] ( lgr_sbc)( 600) (#119) SBCRoutesIterator Deallocated. 09:54:00.738 10.151.3.14 local0.notice [S=925] [SID=58c3aa:27:9] ( lgr_sbc)( 601) (#129) FEATURE Deallocated. 09:54:00.739 10.151.3.14 local0.notice [S=926] [SID=58c3aa:27:9] ( lgr_sbc)( 602) CallAdmission::Remove: Type INVITE LegType: SBC Direction Out IP group 0 (Caller Addr): 0 SRD 0 (DefaultSRD) SRD ovflw:0 SipInterface 0 (SBC): 0 used unres:0 09:54:00.740 10.151.3.14 local0.notice [S=927] [SID=58c3aa:27:9] ( media_service)( 603) ServicesMngr: Deallocate SBC leg. current active: 1 and max is: 10 09:54:00.741 10.151.3.14 local0.notice [S=928] [SID=58c3aa:27:9] ( lgr_call)( 604) (#24) CALL Deallocated. 09:54:00.743 10.151.3.14 local0.notice [S=929] [SID=58c3aa:27:10] ( sip_stack)( 605) SIPCall(#4665) changes state from Disconnected to Idle 09:54:00.743 10.151.3.14 local0.notice [S=930] [SID=58c3aa:27:10] ( lgr_flow)( 606) | | ACSIPStack::ReturnTU - (#4665) 09:54:00.744 10.151.3.14 local0.notice [S=931] [SID=58c3aa:27:10] ( lgr_flow)( 607) (#5344) gwSession Deallocated. 09:54:00.747 10.151.3.14 local0.notice [S=932] [SID=58c3aa:27:9] ( sip_stack)( 608) ---- Incoming SIP Message from 10.151.3.13:5060 to SIPInterface #0 (SBC) UDP TO(#0) ---- 09:54:00.748 10.151.3.14 local0.notice [S=933] [SID=58c3aa:27:9] ACK sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.13:5060;branch=z9hG4bK-524287-1---4599362ea8638557;rport Max-Forwards: 70 To: ;tag=1c1656691180 From: "05333096548";tag=f11de856 Call-ID: 88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk CSeq: 1 ACK Content-Length: 0 09:54:00.749 10.151.3.14 local0.notice [S=934] [SID=58c3aa:27:9] ( sip_stack)( 609) New SIPMessage created - (#95) 09:54:00.749 10.151.3.14 local0.notice [S=935] [SID=58c3aa:27:9] ( sip_stack)( 610) UdpRtxMngr::Remove 487 Response 1 INVITE 09:54:00.753 10.151.3.14 local0.notice [S=936] [SID=58c3aa:27:9] ( lgr_flow)( 611) | |(SIPTU(#4667)ACK State:Disconnected(88254MmUyY2ViYzdiZTYxNmM4M2ViMmUxYTI2MjZmMjk5Yjk) 09:54:00.754 10.151.3.14 local0.notice [S=937] [SID=58c3aa:27:9] ( sip_stack)( 612) Resource SIPMessage deleted - (#95) 09:54:02.044 10.151.3.14 local0.warn [S=938] [BID=58c3aa:27] ( sip_stack)( 613) !! [ERROR] AcSIPParser [SIP Message Headers] Parse error: "Unexpected symbol '?'. Expected token". (L:1,C:1) 09:54:02.044 10.151.3.14 local0.notice [S=939] [BID=58c3aa:27] ( sip_stack)( 614) One of the basic headers (To, From, CSeq, Call-Id, Via) is missing in the message 09:54:02.045 10.151.3.14 local0.warn [S=940] [BID=58c3aa:27] ( sip_stack)( 615) !! [ERROR] SIPStackEngine::HandleReceivedMessage - Basic error in Message 09:54:02.046 10.151.3.14 local0.notice [S=941] [BID=58c3aa:27] ( sip_stack)( 616) ---- Incoming SIP Message from 10.151.3.13:5060 to SIPInterface #0 (SBC) UDP TO(#0) ---- 09:54:02.047 10.151.3.14 local0.notice [S=942] [BID=58c3aa:27] 09:54:02.048 10.151.3.14 local0.notice [S=943] [BID=58c3aa:27] ( sip_stack)( 617) New SIPMessage created - (#94) 09:54:02.049 10.151.3.14 local0.notice [S=944] [BID=58c3aa:27] ( sip_stack)( 618) Resource SIPMessage deleted - (#94) 09:54:02.050 10.151.3.14 local0.notice [S=945] [BID=58c3aa:27] ( lgr_flow)( 619) (#0)IDSMngr <- (#0): IDSReportEvent 09:54:05.683 10.151.3.14 local0.notice [S=946] [SID=58c3aa:27:9] ( sip_stack)( 620) SIPCall(#4667) changes state from Disconnected to Idle 09:54:05.684 10.151.3.14 local0.notice [S=947] [SID=58c3aa:27:9] ( lgr_flow)( 621) | | ACSIPStack::ReturnTU - (#4667) 09:54:27.663 10.151.3.14 local0.notice [S=948] [SID=58c3aa:27:9] ( sip_stack)( 622) New SIPMessage created - (#93) 09:54:27.664 10.151.3.14 local0.notice [S=949] [SID=58c3aa:27:9] ( lgr_flow)( 623) | |(SIPTU(#4664)DISCONNECT_REQ State:Cancelling(394224111120100315@10.151.3.14) 09:54:27.665 10.151.3.14 local0.notice [S=950] [SID=58c3aa:27:9] ( sip_stack)( 624) SIPCall can't handle Disconnect event in state Cancelling 09:54:27.665 10.151.3.14 local0.notice [S=951] [SID=58c3aa:27:9] ( sip_stack)( 625) Resource SIPMessage deleted - (#93) 09:54:27.667 10.151.3.14 local0.notice [S=952] [SID=58c3aa:27:9] ( sip_stack)( 626) SIPCall(#4664) changes state from Cancelling to Disconnected 09:54:27.668 10.151.3.14 local0.notice [S=953] [SID=58c3aa:27:9] ( sip_stack)( 627) AcSIPCall(#4664)::TransactionFail - InviteClientTransaction(#986) failed sending message with CSeq 1 INVITE, the cause is Transport Error 09:54:27.668 10.151.3.14 local0.notice [S=954] [SID=58c3aa:27:9] ( sip_stack)( 628) SIPCall(#4664) changes state from Disconnected to Idle 09:54:27.669 10.151.3.14 local0.notice [S=955] [SID=58c3aa:27:9] ( lgr_flow)( 629) | | ACSIPStack::ReturnTU - (#4664) 09:54:27.670 10.151.3.14 local0.notice [S=956] [SID=58c3aa:27:9] ( lgr_flow)( 630) (#5345) gwSession Deallocated. 09:54:32.023 10.151.3.14 local0.warn [S=957] [BID=58c3aa:27] ( sip_stack)( 631) !! [ERROR] AcSIPParser [SIP Message Headers] Parse error: "Unexpected symbol '?'. Expected token". (L:1,C:1) 09:54:32.024 10.151.3.14 local0.notice [S=958] [BID=58c3aa:27] ( sip_stack)( 632) One of the basic headers (To, From, CSeq, Call-Id, Via) is missing in the message 09:54:32.025 10.151.3.14 local0.warn [S=959] [BID=58c3aa:27] ( sip_stack)( 633) !! [ERROR] SIPStackEngine::HandleReceivedMessage - Basic error in Message 09:54:32.026 10.151.3.14 local0.notice [S=960] [BID=58c3aa:27] ( sip_stack)( 634) ---- Incoming SIP Message from 10.151.3.13:5060 to SIPInterface #0 (SBC) UDP TO(#0) ---- 09:54:32.026 10.151.3.14 local0.notice [S=961] [BID=58c3aa:27] 09:54:32.027 10.151.3.14 local0.notice [S=962] [BID=58c3aa:27] ( sip_stack)( 635) New SIPMessage created - (#92) 09:54:32.028 10.151.3.14 local0.notice [S=963] [BID=58c3aa:27] ( sip_stack)( 636) Resource SIPMessage deleted - (#92) 09:54:32.029 10.151.3.14 local0.notice [S=964] [BID=58c3aa:27] ( lgr_flow)( 637) (#0)IDSMngr <- (#0): IDSReportEvent