10:14:49.509 10.151.3.14 local0.notice [S=959] [SID=58c3aa:28:9] ( sip_stack)( 631) New SIPMessage created - (#91) 10:14:49.510 10.151.3.14 local0.notice [S=960] [SID=58c3aa:28:9] ( lgr_flow)( 632) | |(SIPTU(#4664)DISCONNECT_REQ State:Cancelling(15855952901120100349@10.151.3.14) 10:14:49.511 10.151.3.14 local0.notice [S=961] [SID=58c3aa:28:9] ( sip_stack)( 633) SIPCall can't handle Disconnect event in state Cancelling 10:14:49.512 10.151.3.14 local0.notice [S=962] [SID=58c3aa:28:9] ( sip_stack)( 634) Resource SIPMessage deleted - (#91) 10:14:49.513 10.151.3.14 local0.notice [S=963] [SID=58c3aa:28:9] ( sip_stack)( 635) SIPCall(#4664) changes state from Cancelling to Disconnected 10:14:49.514 10.151.3.14 local0.notice [S=964] [SID=58c3aa:28:9] ( sip_stack)( 636) AcSIPCall(#4664)::TransactionFail - InviteClientTransaction(#986) failed sending message with CSeq 1 INVITE, the cause is Transport Error 10:14:49.515 10.151.3.14 local0.notice [S=965] [SID=58c3aa:28:9] ( sip_stack)( 637) SIPCall(#4664) changes state from Disconnected to Idle 10:14:49.515 10.151.3.14 local0.notice [S=966] [SID=58c3aa:28:9] ( lgr_flow)( 638) | | ACSIPStack::ReturnTU - (#4664) 10:14:49.516 10.151.3.14 local0.notice [S=967] [SID=58c3aa:28:9] ( lgr_flow)( 639) (#5345) gwSession Deallocated. 10:14:51.406 10.151.3.14 local0.notice [S=968] [SID=58c3aa:28:12] ( lgr_flow)( 640) (#5342) gwSession Allocated. Handle:12DA1948; Global session ID: 6cafc7427f6b0c71 10:14:51.408 10.151.3.14 local0.notice [S=969] [SID=58c3aa:28:12] ( sip_sbc)( 641) SIPSBCMngr::GetStackEP - No user is found in DB 10:14:51.409 10.151.3.14 local0.notice [S=970] [SID=58c3aa:28:12] ( lgr_sbc)( 642) (#118) SBCRoutesIterator Allocated. 10:14:51.409 10.151.3.14 local0.notice [S=971] [SID=58c3aa:28:12] ( lgr_sbc)( 643) Classification: Resolving to default IP group of SipInterface #0. 10:14:51.410 10.151.3.14 local0.notice [S=972] [SID=58c3aa:28:12] ( lgr_sbc)( 644) Classification Succeeded - Source IP Group #0 (Caller Addr), - Dest Routing Policy #0 10:14:51.411 10.151.3.14 local0.notice [S=973] [SID=58c3aa:28:12] ( lgr_flow)( 645) (#118)SBCRoutesIterator::Change State From: InitialCSRRouting To : InitialRouting 10:14:51.411 10.151.3.14 local0.notice [S=974] [SID=58c3aa:28:12] ( lgr_flow)( 646) (#118)SBCRoutesIterator::Change State From: InitialRouting To : AlternativeRouting 10:14:51.412 10.151.3.14 local0.notice [S=975] [SID=58c3aa:28:12] ( lgr_flow)( 647) (#-1)SBCAdmissionControlMngr <- (#118)SBCRoutesIterator: SBC_ADMIT_DIALOGS_EV 10:14:51.414 10.151.3.14 local0.notice [S=976] [SID=58c3aa:28:12] ( lgr_sbc)( 648) 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 10:14:51.415 10.151.3.14 local0.notice [S=977] [SID=58c3aa:28:12] ( media_service)( 649) ServicesMngr: Allocate SBC leg. current active: 0 and max is: 10 10:14:51.416 10.151.3.14 local0.notice [S=978] [SID=58c3aa:28:12] ( lgr_sbc)( 650) 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 10:14:51.416 10.151.3.14 local0.notice [S=979] [SID=58c3aa:28:12] ( media_service)( 651) ServicesMngr: Allocate SBC leg. current active: 1 and max is: 10 10:14:51.417 10.151.3.14 local0.notice [S=980] [SID=58c3aa:28:12] ( lgr_flow)( 652) (#118)SBCRoutesIterator::Next route found: Rule #1, Route by: IPGroup , IP Group ID: 1 (GW), Live:True 10:14:51.418 10.151.3.14 local0.notice [S=981] [SID=58c3aa:28:12] ( lgr_sbc)( 653) Routing Succeeded -IP2IPRouting Rule #1 10:14:51.419 10.151.3.14 local0.notice [S=982] [SID=58c3aa:28:12] ( sip_stack)( 654) ---- Incoming SIP Message from 10.151.3.13:5060 to SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:51.420 10.151.3.14 local0.notice [S=983] [SID=58c3aa:28:12] INVITE sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.13:5060;branch=z9hG4bK-524287-1---ebb395234ac7652e;rport Max-Forwards: 70 Contact: To: From: "05333096548";tag=ce219c37 Call-ID: 88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q 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=- 13156326891140690 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 56038 RTP/AVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv 10:14:51.421 10.151.3.14 local0.notice [S=984] [SID=58c3aa:28:12] ( sip_stack)( 655) New SIPMessage created - (#90) 10:14:51.421 10.151.3.14 local0.notice [S=985] [SID=58c3aa:28:12] ( sip_stack)( 656) New SIPSBCCallLeg created - (#4056) 10:14:51.422 10.151.3.14 local0.notice [S=986] [SID=58c3aa:28:12] ( sip_stack)( 657) New AcSIPCall created - (#4663) 10:14:51.425 10.151.3.14 local0.notice [S=987] [SID=58c3aa:28:12] ( sip_stack)( 658) AcTransactionUser::AddMessageToQueue: Queueing message 10:14:51.426 10.151.3.14 local0.notice [S=988] [SID=58c3aa:28:12] ( lgr_flow)( 659) | | | (#4056)SIPSBCCallLeg <- (#4056)AcSBCCallAPI: SBCNewCallData 10:14:51.427 10.151.3.14 local0.notice [S=989] [SID=58c3aa:28:12] ( lgr_call)( 660) (#23) CALL Allocated. 10:14:51.434 10.151.3.14 local0.notice [S=990] [SID=58c3aa:28:12] ( lgr_stk_ses)( 661) SESSION (#4056) SendToCall - event: NEW_CALL_EV m_Call(#23) 10:14:51.435 10.151.3.14 local0.notice [S=991] [SID=58c3aa:28:12] ( lgr_flow)( 662) | | (#23)SBCVoiceCall <- (#4056)SIPSBCCallLeg: NEW_CALL_EV 10:14:51.435 10.151.3.14 local0.notice [S=992] [SID=58c3aa:28:12] ( lgr_flow)( 663) | | (#23) SBCCall changing states from:IdleState to:NewCallState 10:14:51.436 10.151.3.14 local0.notice [S=993] [SID=58c3aa:28:12] ( lgr_call)( 664) 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 10:14:51.437 10.151.3.14 local0.notice [S=994] [SID=58c3aa:28:12] ( lgr_flow)( 665) ServicesMngr::GetSBCEndPoint PhoneNum = 1000 10:14:51.437 10.151.3.14 local0.notice [S=995] [SID=58c3aa:28:12] ( media_service)( 666) (#2037) CONTROLLER Allocated. 10:14:51.439 10.151.3.14 local0.notice [S=996] [SID=58c3aa:28:12] ( lgr_flow)( 667) (#4056)SBCParticipantEndPoint <- (#23)SBCVoiceCall: NEW_CALL_EV 10:14:51.439 10.151.3.14 local0.notice [S=997] [SID=58c3aa:28:12] ( lgr_flow)( 668) (#4056) SBCParticipantEndPoint changing states from:IdleState to:NewCallState 10:14:51.440 10.151.3.14 local0.notice [S=998] [SID=58c3aa:28:12] ( lgr_flow)( 669) (#2037)SBCController <- (#4056)SBCParticipantEndPoint: NEW_CALL_EV 10:14:51.441 10.151.3.14 local0.notice [S=999] [SID=58c3aa:28:12] ( lgr_flow)( 670) | | (#2037) SBCController changing states from:IdleState to:NewCallState 10:14:51.442 10.151.3.14 local0.notice [S=1000] [SID=58c3aa:28:12] ( lgr_flow)( 671) (#4057)SBCParticipantEndPoint <- (#2037)SBCController: NEW_CALL_EV 10:14:51.443 10.151.3.14 local0.notice [S=1001] [SID=58c3aa:28:12] ( lgr_flow)( 672) (#4057) SBCParticipantEndPoint changing states from:IdleState to:NewCallState 10:14:51.444 10.151.3.14 local0.notice [S=1002] [SID=58c3aa:28:12] ( lgr_sbc)( 673) (#128) FEATURE Allocated. 10:14:51.445 10.151.3.14 local0.notice [S=1003] [SID=58c3aa:28:12] ( lgr_flow)( 674) | (#128)SBCCallPlacementFeature <- (#4057)SBCParticipantEndPoint: NEW_CALL_EV 10:14:51.446 10.151.3.14 local0.notice [S=1004] [SID=58c3aa:28:12] ( lgr_call)( 675) (#22) CALL Allocated. 10:14:51.447 10.151.3.14 local0.notice [S=1005] [SID=58c3aa:28:12] ( lgr_flow)( 676) | | (#22)SBCVoiceCall <- (#128)SBCCallPlacementFeature: NEW_CALL_EV 10:14:51.449 10.151.3.14 local0.notice [S=1006] [SID=58c3aa:28:12] ( lgr_flow)( 677) | | (#22) SBCCall changing states from:IdleState to:NewCallState 10:14:51.450 10.151.3.14 local0.notice [S=1007] [SID=58c3aa:28:12] ( lgr_call)( 678) 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 10:14:51.450 10.151.3.14 local0.notice [S=1008] [SID=58c3aa:28:12] ( lgr_stk_mngr)( 679) (#4055)SIPSBCCallLeg Allocated. 10:14:51.451 10.151.3.14 local0.notice [S=1009] [SID=58c3aa:28:12] ( lgr_flow)( 680) | | | (#4055)SIPSBCCallLeg <- (#22)SBCVoiceCall: NEW_CALL_EV 10:14:51.452 10.151.3.14 local0.notice [S=1010] [SID=58c3aa:28:12] ( sip_stack)( 681) AcTransactionUser::HandleQueuedMessages: Handle a queued message 10:14:51.453 10.151.3.14 local0.notice [S=1011] [SID=58c3aa:28:12] ( lgr_flow)( 682) | |(SIPTU(#4663)INVITE State:Idle(88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q) 10:14:51.454 10.151.3.14 local0.notice [S=1012] [SID=58c3aa:28:12] ( sip_sbc)( 683) SIPSBCMngr::GetStackEP - No user is found in DB 10:14:51.455 10.151.3.14 local0.notice [S=1013] [SID=58c3aa:28:12] ( sip_stack)( 684) TU(#4663) SIPSessionTimer::HandleSTRequest - Session-Timer mode: TRANSPARENT 10:14:51.461 10.151.3.14 local0.notice [S=1014] [SID=58c3aa:28:12] ( sip_stack)( 685) SIPCall(#4663) changes state from Idle to Invited 10:14:51.461 10.151.3.14 local0.notice [S=1015] [SID=58c3aa:28:12] ( lgr_flow)( 686) | | | (#4056)SIPSBCCallLeg <- (#4056)AcSBCCallAPI: SBCSetupData 10:14:51.462 10.151.3.14 local0.notice [S=1016] [SID=58c3aa:28:12] ( sip_stack)( 687) SDPBody::New - id = (#50) 10:14:51.464 10.151.3.14 local0.notice [S=1017] [SID=58c3aa:28:12] ( sip_stack)( 688) SDPBodyMedia::New - id = (#457) 10:14:51.464 10.151.3.14 local0.notice [S=1018] [SID=58c3aa:28:12] ( lgr_flow)( 689) (#144) MediaMetaDataList Allocated. 10:14:51.465 10.151.3.14 local0.notice [S=1019] [SID=58c3aa:28:12] ( lgr_stk_ses)( 690) SBCOfferAnswerMngr(#4056) changes state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 10:14:51.466 10.151.3.14 local0.notice [S=1020] [SID=58c3aa:28:12] ( media_connect)( 691) (#3) MediaResourcesConnector Allocated. 10:14:51.466 10.151.3.14 local0.notice [S=1021] [SID=58c3aa:28:12] ( media_service)( 692) (#6) MediaResourceAdapter Allocated. OrigSide IP 10:14:51.468 10.151.3.14 local0.notice [S=1022] [SID=58c3aa:28:12] ( lgr_stk_ses)( 693) SBCSDPDTMFFeature::UpdateMediaDTMFConfig: m_RFC=0 m_Alternative=0 m_PeerRFC=0 m_PeerAlternative=0 m_SendMultipleDTMFMethods=False m_PeerSendMultipleDTMFMethods=False 10:14:51.469 10.151.3.14 local0.notice [S=1023] [SID=58c3aa:28:12] ( lgr_stk_ses)( 694) SBCSDPANATFeature::UpdateANATConfig - Behavior isIP_VERSION_ONLY_IPV4 10:14:51.470 10.151.3.14 local0.notice [S=1024] [SID=58c3aa:28:12] ( media_service)( 695) (#16) RTPStreamResource Allocated. Sibling ID: (#-1) 10:14:51.471 10.151.3.14 local0.notice [S=1025] [SID=58c3aa:28:12] ( media_service)( 696) (#6) ChannelResource Allocated. 10:14:51.471 10.151.3.14 local0.notice [S=1026] [SID=58c3aa:28:12] ( media_service)( 697) (#6) ChannelResource belongs to call #23 10:14:51.472 10.151.3.14 local0.notice [S=1027] [SID=58c3aa:28:12] ( lgr_flow)( 698) (#6)ChannelResource::AllocateMediaIpPorts RealmIndex(0) port(6040) Allocated. 10:14:51.473 10.151.3.14 local0.notice [S=1028] [SID=58c3aa:28:12] ( lgr_stk_mngr)( 699) New BufferSDPBody created - (#173) 10:14:51.474 10.151.3.14 local0.notice [S=1029] [SID=58c3aa:28:12] ( sip_stack)( 700) SDPBodyMedia::New - id = (#456) 10:14:51.475 10.151.3.14 local0.notice [S=1030] [SID=58c3aa:28:12] ( lgr_flow)( 701) | |(SIPTU(#4663)TRYING_REQ State:Invited(88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q) 10:14:51.476 10.151.3.14 local0.notice [S=1031] [SID=58c3aa:28:12] ( sip_stack)( 702) New SIPMessage created - (#89) 10:14:51.477 10.151.3.14 local0.notice [S=1032] [SID=58c3aa:28:12] ( sip_stack)( 703) ---- Outgoing SIP Message to 10.151.3.13:5060 from SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:51.479 10.151.3.14 local0.notice [S=1033] [SID=58c3aa:28:12] 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---ebb395234ac7652e From: "05333096548" ;tag=ce219c37 To: Call-ID: 88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q CSeq: 1 INVITE Server: M500/v.7.20A.154.052 Content-Length: 0 10:14:51.479 10.151.3.14 local0.notice [S=1034] [SID=58c3aa:28:12] ( lgr_flow)( 705) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:51.480 10.151.3.14 local0.notice [S=1035] [SID=58c3aa:28:12] ( sip_stack)( 704) Resource SIPMessage deleted - (#89) 10:14:51.481 10.151.3.14 local0.notice [S=1036] [SID=58c3aa:28:12] ( lgr_flow)( 706) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:51.482 10.151.3.14 local0.notice [S=1037] [SID=58c3aa:28:12] ( lgr_stk_ses)( 707) SESSION (#4056) SendToCall - event: SETUP_EV m_Call(#23) 10:14:51.489 10.151.3.14 local0.notice [S=1038] [SID=58c3aa:28:12] ( lgr_flow)( 708) | | (#23)SBCVoiceCall <- (#4056)SIPSBCCallLeg: SETUP_EV 10:14:51.490 10.151.3.14 local0.notice [S=1039] [SID=58c3aa:28:12] ( lgr_flow)( 709) | | (#23) SBCCall changing states from:NewCallState to:InitiatedState 10:14:51.491 10.151.3.14 local0.notice [S=1040] [SID=58c3aa:28:12] ( lgr_flow)( 710) (#4056)SBCParticipantEndPoint <- (#23)SBCVoiceCall: SETUP_EV 10:14:51.491 10.151.3.14 local0.notice [S=1041] [SID=58c3aa:28:12] ( lgr_flow)( 711) (#4056) SBCParticipantEndPoint changing states from:NewCallState to:InitiatedState 10:14:51.492 10.151.3.14 local0.notice [S=1042] [SID=58c3aa:28:12] ( lgr_flow)( 712) (#2037)SBCController <- (#4056)SBCParticipantEndPoint: SETUP_EV 10:14:51.493 10.151.3.14 local0.notice [S=1043] [SID=58c3aa:28:12] ( lgr_flow)( 713) | | (#2037) SBCController changing states from:NewCallState to:EstablishingState 10:14:51.494 10.151.3.14 local0.notice [S=1044] [SID=58c3aa:28:12] ( lgr_flow)( 714) MediaResourcesConnector(#3)Handle SBCMediaCarrier from Controller 10:14:51.495 10.151.3.14 local0.notice [S=1045] [SID=58c3aa:28:12] ( lgr_flow)( 715) (#4057)SBCParticipantEndPoint <- (#2037)SBCController: SETUP_EV 10:14:51.496 10.151.3.14 local0.notice [S=1046] [SID=58c3aa:28:12] ( lgr_flow)( 716) (#4057) SBCParticipantEndPoint changing states from:NewCallState to:InitiatedState 10:14:51.496 10.151.3.14 local0.notice [S=1047] [SID=58c3aa:28:12] ( lgr_flow)( 717) | (#128)SBCCallPlacementFeature <- (#4057)SBCParticipantEndPoint: SETUP_EV 10:14:51.497 10.151.3.14 local0.notice [S=1048] [SID=58c3aa:28:12] ( lgr_flow)( 718) (#143) MediaMetaDataList Allocated. 10:14:51.499 10.151.3.14 local0.notice [S=1049] [SID=58c3aa:28:12] ( lgr_flow)( 719) (#128) SBCCallPlacementFeature changing states from: Idle to: Initiated 10:14:51.499 10.151.3.14 local0.notice [S=1050] [SID=58c3aa:28:12] ( lgr_flow)( 720) | | (#22)SBCVoiceCall <- (#128)SBCCallPlacementFeature: SETUP_EV 10:14:51.500 10.151.3.14 local0.notice [S=1051] [SID=58c3aa:28:12] ( sip_stack)( 721) SDPBody::New - id = (#49) 10:14:51.501 10.151.3.14 local0.notice [S=1052] [SID=58c3aa:28:12] ( sip_stack)( 722) SDPBodyMedia::New - id = (#455) 10:14:51.502 10.151.3.14 local0.notice [S=1053] [SID=58c3aa:28:12] ( lgr_flow)( 723) | | (#22) SBCCall changing states from:NewCallState to:InitiatedState 10:14:51.503 10.151.3.14 local0.notice [S=1054] [SID=58c3aa:28:12] ( lgr_flow)( 724) | | | (#4055)SIPSBCCallLeg <- (#22)SBCVoiceCall: SETUP_EV 10:14:51.504 10.151.3.14 local0.notice [S=1055] [SID=58c3aa:28:12] ( lgr_stk_ses)( 725) SIPSBCCallLeg(#4055) - Route by dest IPGroup: #1 10:14:51.505 10.151.3.14 local0.notice [S=1056] [SID=58c3aa:28:12] ( lgr_flow)( 726) | | new AcSIPCall created - (#4662) 10:14:51.505 10.151.3.14 local0.notice [S=1057] [SID=58c3aa:28:12] ( lgr_stk_ses)( 727) SBCOfferAnswerMngr(#4055) changes state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERING 10:14:51.506 10.151.3.14 local0.notice [S=1058] [SID=58c3aa:28:12] ( media_service)( 728) (#5) MediaResourceAdapter Allocated. OrigSide TEL 10:14:51.508 10.151.3.14 local0.notice [S=1059] [SID=58c3aa:28:12] ( lgr_stk_ses)( 729) SBCSDPDTMFFeature::UpdateMediaDTMFConfig: m_RFC=0 m_Alternative=0 m_PeerRFC=0 m_PeerAlternative=0 m_SendMultipleDTMFMethods=False m_PeerSendMultipleDTMFMethods=False 10:14:51.509 10.151.3.14 local0.notice [S=1060] [SID=58c3aa:28:12] ( lgr_stk_ses)( 730) SBCSDPANATFeature::UpdateANATConfig - Behavior isIP_VERSION_ONLY_IPV4 10:14:51.510 10.151.3.14 local0.notice [S=1061] [SID=58c3aa:28:12] ( media_service)( 731) (#15) RTPStreamResource Allocated. Sibling ID: (#-1) 10:14:51.516 10.151.3.14 local0.notice [S=1062] [SID=58c3aa:28:12] ( media_service)( 732) (#5) ChannelResource Allocated. 10:14:51.517 10.151.3.14 local0.notice [S=1063] [SID=58c3aa:28:12] ( media_service)( 733) (#5) ChannelResource belongs to call #22 10:14:51.518 10.151.3.14 local0.notice [S=1064] [SID=58c3aa:28:12] ( lgr_flow)( 734) (#5)ChannelResource::AllocateMediaIpPorts RealmIndex(0) port(6050) Allocated. 10:14:51.519 10.151.3.14 local0.notice [S=1065] [SID=58c3aa:28:12] ( lgr_flow)( 735) #MediaResourcesConnector::AllocateMediaResources 10:14:51.520 10.151.3.14 local0.notice [S=1066] [SID=58c3aa:28:12] ( media_connect)( 736) ConnectionData::CalculateResourcesForRTPForwarding Leading:NONE Opposite:NONE MediationLevel:RTP 10:14:51.521 10.151.3.14 local0.notice [S=1067] [SID=58c3aa:28:12] ( lgr_flow)( 737) #MediaResourcesConnector::AllocateMediaResources 10:14:51.521 10.151.3.14 local0.notice [S=1068] [SID=58c3aa:28:12] ( media_connect)( 738) ConnectionData::CalculateResourcesForRTPForwarding Leading:NONE Opposite:NONE MediationLevel:RTP 10:14:51.522 10.151.3.14 local0.notice [S=1069] [SID=58c3aa:28:12] ( lgr_stk_ses)( 739) DirectMediaDB::StoreSDP added DirectMediaData to Database IP:10.151.3.14 Port:6050 SDP:(#173) 10:14:51.523 10.151.3.14 local0.notice [S=1070] [SID=58c3aa:28:12] ( lgr_flow)( 740) (#142) MediaMetaDataList Allocated. 10:14:51.524 10.151.3.14 local0.notice [S=1071] [SID=58c3aa:28:12] ( lgr_stk_mngr)( 741) New BufferSDPBody created - (#172) 10:14:51.526 10.151.3.14 local0.notice [S=1072] [SID=58c3aa:28:12] ( sip_stack)( 742) SDPBodyMedia::New - id = (#454) 10:14:51.526 10.151.3.14 local0.notice [S=1073] [SID=58c3aa:28:12] ( sip_sbc)( 743) SIPSBCMngr::GetStackEP - No user is found in DB 10:14:51.529 10.151.3.14 local0.notice [S=1074] [SID=58c3aa:28:12] ( lgr_flow)( 744) | |(SIPTU(#4662)SETUP_REQ State:Idle() 10:14:51.530 10.151.3.14 local0.notice [S=1075] [SID=58c3aa:28:12] ( sip_stack)( 745) SIPCall(#4662) changes state from Idle to Inviting 10:14:51.531 10.151.3.14 local0.notice [S=1076] [SID=58c3aa:28:12] ( sip_stack)( 746) TU(#4662) SIPSessionTimer::FillSTRequestData - Session-Timer mode: TRANSPARENT 10:14:51.532 10.151.3.14 local0.notice [S=1077] [SID=58c3aa:28:12] ( lgr_stack)( 747) [ManSet: 1, Rule: 2] - Executed on msgId:90 (Condition was False) 10:14:51.533 10.151.3.14 local0.notice [S=1078] [SID=58c3aa:28:12] ( sip_stack)( 748) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 6 Dest: 10.151.3.14:5070 CallID: (12598589641120100423@10.151.3.14) 10:14:51.534 10.151.3.14 local0.notice [S=1079] [SID=58c3aa:28:12] ( sip_stack)( 749) ---- Outgoing SIP Message to 10.151.3.14:5070 from SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:51.534 10.151.3.14 local0.notice [S=1080] [SID=58c3aa:28:12] INVITE sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1519982499 Max-Forwards: 69 From: "05333096548" ;tag=1c646854607 To: Call-ID: 12598589641120100423@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: 208 v=0 o=- 136085248 667281220 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 6050 RTP/AVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv 10:14:51.535 10.151.3.14 local0.notice [S=1081] [SID=58c3aa:28:12] ( lgr_flow)( 750) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:51.536 10.151.3.14 local0.notice [S=1082] [SID=58c3aa:28:12] ( lgr_flow)( 751) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:51.537 10.151.3.14 local0.notice [S=1083] [SID=58c3aa:28:12] ( sip_stack)( 752) SDPBody::Delete - id = (#49) 10:14:51.543 10.151.3.14 local0.notice [S=1084] [SID=58c3aa:28:12] ( sip_stack)( 753) SDPBodyMedia::Delete - id = (#455) 10:14:51.544 10.151.3.14 local0.notice [S=1085] [SID=58c3aa:28:13] ( lgr_flow)( 754) (#5341) gwSession Allocated. Handle:12DA1968; Global session ID: 36b5a8f4475f9c3a 10:14:51.545 10.151.3.14 local0.notice [S=1086] [SID=58c3aa:28:13] ( sip_stack)( 755) ---- Incoming SIP Message from 10.151.3.14:5060 to SIPInterface #1 (GW) UDP TO(#1) ---- 10:14:51.546 10.151.3.14 local0.notice [S=1087] [SID=58c3aa:28:13] INVITE sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1519982499 Max-Forwards: 69 From: "05333096548" ;tag=1c646854607 To: Call-ID: 12598589641120100423@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: 208 v=0 o=- 136085248 667281220 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 6050 RTP/AVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv 10:14:51.547 10.151.3.14 local0.notice [S=1088] [SID=58c3aa:28:13] ( sip_stack)( 756) New SIPMessage created - (#88) 10:14:51.549 10.151.3.14 local0.notice [S=1089] [SID=58c3aa:28:13] ( sip_stack)( 757) New AcSIPCallAPI created - (#26) + New SIPStackSession created - (#19) 10:14:51.550 10.151.3.14 local0.notice [S=1090] [SID=58c3aa:28:13] ( sip_stack)( 758) New AcSIPCall created - (#4661) 10:14:51.550 10.151.3.14 local0.notice [S=1091] [SID=58c3aa:28:13] ( lgr_flow)( 759) | |(SIPTU(#4661)INVITE State:Idle(12598589641120100423@10.151.3.14) 10:14:51.551 10.151.3.14 local0.notice [S=1092] [SID=58c3aa:28:13] ( sip_sbc)( 760) SIPSBCMngr::GetStackEP - No user is found in DB 10:14:51.552 10.151.3.14 local0.notice [S=1093] [SID=58c3aa:28:13] ( sip_stack)( 761) TU(#4661) SIPSessionTimer::HandleSTRequest - Session-Timer mode: SUPPORTED 10:14:51.553 10.151.3.14 local0.notice [S=1094] [SID=58c3aa:28:13] ( sip_stack)( 762) SIPCall(#4661) changes state from Idle to Invited 10:14:51.554 10.151.3.14 local0.notice [S=1095] [SID=58c3aa:28:13] ( sip_stack)( 763) SDPBody::New - id = (#48) 10:14:51.555 10.151.3.14 local0.notice [S=1096] [SID=58c3aa:28:13] ( sip_stack)( 764) SDPBodyMedia::New - id = (#453) 10:14:51.556 10.151.3.14 local0.notice [S=1097] [SID=58c3aa:28:13] ( lgr_flow)( 765) | | | (#19)SIPStackSession <- (#26)AcSIPCallAPI: SIPSetupData 10:14:51.557 10.151.3.14 local0.notice [S=1098] [SID=58c3aa:28:13] ( lgr_call)( 766) (#21) CALL Allocated. 10:14:51.558 10.151.3.14 local0.notice [S=1099] [SID=58c3aa:28:13] ( sip_sbc)( 767) SIPSBCMngr::GetStackEP - No user is found in DB 10:14:51.559 10.151.3.14 local0.notice [S=1100] [SID=58c3aa:28:13] ( lgr_stk_ses)( 768) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 10:14:51.560 10.151.3.14 local0.notice [S=1101] [SID=58c3aa:28:13] ( lgr_stk_ses)( 769) SESSION (#19) SendToCall - event: NEW_CALL_EV m_Call(#21) 10:14:51.560 10.151.3.14 local0.notice [S=1102] [SID=58c3aa:28:13] ( lgr_flow)( 770) | | (#21):NEW_CALL_EV:(12598589641120100423@10.151.3.14) 10:14:51.561 10.151.3.14 local0.notice [S=1103] [SID=58c3aa:28:13] ( lgr_flow)( 771) | | (#21):Call changing states from:IdleState to:NewCallState_IP2Tel 10:14:51.562 10.151.3.14 local0.notice [S=1104] [SID=58c3aa:28:13] ( lgr_flow)( 772) ServicesMngr::GetEndPoint PhoneNum = 1000 10:14:51.563 10.151.3.14 local0.notice [S=1105] [SID=58c3aa:28:13] ( lgr_gw_engine)( 773) GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:1000 DstPfx:* SrcNum:05333096548 SrcPfx:* SrcIp:177668878 SrcIpPfx: SrcIpGroup:-1 10:14:51.564 10.151.3.14 local0.notice [S=1106] [SID=58c3aa:28:13] ( lgr_psbrdif)( 774) Current trunks status: 10:14:51.565 10.151.3.14 local0.notice [S=1107] [SID=58c3aa:28:13] ( lgr_call)( 775) Call::SetProfiledCallParams (#21) Found 4 Common Coders For Call 10:14:51.571 10.151.3.14 local0.notice [S=1108] [SID=58c3aa:28:13] ( lgr_call)( 776) Call (#21) Coder g711Alaw64k20 : 20 10:14:51.572 10.151.3.14 local0.notice [S=1109] [SID=58c3aa:28:13] ( lgr_call)( 777) Call (#21) Coder g711Ulaw64k20 : 20 10:14:51.574 10.151.3.14 local0.notice [S=1110] [SID=58c3aa:28:13] ( lgr_call)( 778) Call (#21) Coder g72920 : 20 10:14:51.575 10.151.3.14 local0.notice [S=1111] [SID=58c3aa:28:13] ( lgr_call)( 779) Call (#21) Coder g72220 : 20 10:14:51.575 10.151.3.14 local0.notice [S=1112] [SID=58c3aa:28:13] ( lgr_profiling)( 780) 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 10:14:51.576 10.151.3.14 local0.notice [S=1113] [SID=58c3aa:28:13] ( lgr_flow)( 781) | #0:NEW_CALL_EV : (12598589641120100423@10.151.3.14) 10:14:51.576 10.151.3.14 local0.notice [S=1114] [SID=58c3aa:28:13] ( lgr_stk_mngr)( 782) New BufferSDPBody created - (#171) 10:14:51.577 10.151.3.14 local0.notice [S=1115] [SID=58c3aa:28:13] ( lgr_flow)( 783) EndPoint::MediaResourceList::AllocateMediaIpPortsByMediaRealmID Perform NEW allocation of Media ports for RealmIndex(0) port(6060) current allocations are:(1) Owner:((#0)) 10:14:51.579 10.151.3.14 local0.notice [S=1116] [SID=58c3aa:28:13] ( sip_stack)( 784) SDPBodyMedia::New - id = (#452) 10:14:51.581 10.151.3.14 local0.notice [S=1117] [SID=58c3aa:28:13] ( sip_stack)( 785) SDPBodyMedia::Delete - id = (#452) 10:14:51.582 10.151.3.14 local0.notice [S=1118] [SID=58c3aa:28:13] ( sip_stack)( 786) Resource BufferSDPBody deleted - (#171) 10:14:51.583 10.151.3.14 local0.notice [S=1119] [SID=58c3aa:28:13] ( lgr_stk_mngr)( 787) New BufferSDPBody created - (#170) 10:14:51.583 10.151.3.14 local0.notice [S=1120] [SID=58c3aa:28:13] ( sip_stack)( 788) SDPBodyMedia::New - id = (#451) 10:14:51.584 10.151.3.14 local0.notice [S=1121] [SID=58c3aa:28:13] ( sip_stack)( 789) SIPSDPSession(#19) - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 10:14:51.585 10.151.3.14 local0.notice [S=1122] [SID=58c3aa:28:13] ( sip_stack)( 790) UpdateChosenMediaByCN - CN as Remote False 10:14:51.586 10.151.3.14 local0.notice [S=1123] [SID=58c3aa:28:13] ( sip_stack)( 791) SDPBodyMedia::Delete - id = (#451) 10:14:51.587 10.151.3.14 local0.notice [S=1124] [SID=58c3aa:28:13] ( sip_stack)( 792) SDPBodyMedia::New - id = (#450) 10:14:51.588 10.151.3.14 local0.notice [S=1125] [SID=58c3aa:28:13] ( lgr_flow)( 793) | |(SIPTU(#4661)TRYING_REQ State:Invited(12598589641120100423@10.151.3.14) 10:14:51.589 10.151.3.14 local0.notice [S=1126] [SID=58c3aa:28:13] ( sip_stack)( 794) New SIPMessage created - (#87) 10:14:51.589 10.151.3.14 local0.notice [S=1127] [SID=58c3aa:28:13] ( sip_stack)( 795) ---- Outgoing SIP Message to 10.151.3.14:5060 from SIPInterface #1 (GW) UDP TO(#1) ---- 10:14:51.590 10.151.3.14 local0.notice [S=1128] [SID=58c3aa:28:13] SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1519982499 From: "05333096548" ;tag=1c646854607 To: Call-ID: 12598589641120100423@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 10:14:51.591 10.151.3.14 local0.notice [S=1129] [SID=58c3aa:28:13] ( lgr_flow)( 796) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:51.592 10.151.3.14 local0.notice [S=1130] [SID=58c3aa:28:13] ( sip_stack)( 797) Resource SIPMessage deleted - (#87) 10:14:51.600 10.151.3.14 local0.notice [S=1131] [SID=58c3aa:28:13] ( lgr_flow)( 798) (#252)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:51.601 10.151.3.14 local0.notice [S=1132] [SID=58c3aa:28:13] ( lgr_stk_ses)( 799) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 10:14:51.601 10.151.3.14 local0.notice [S=1133] [SID=58c3aa:28:13] ( lgr_stk_ses)( 800) SESSION (#19) SendToCall - event: SETUP_EV m_Call(#21) 10:14:51.602 10.151.3.14 local0.notice [S=1134] [SID=58c3aa:28:13] ( lgr_flow)( 801) | | (#21):SETUP (TO:1000, FROM:05333096548):(12598589641120100423@10.151.3.14) 10:14:51.603 10.151.3.14 local0.notice [S=1135] [SID=58c3aa:28:13] ( lgr_flow)( 802) | | (#21):Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel 10:14:51.604 10.151.3.14 local0.notice [S=1136] [SID=58c3aa:28:13] ( lgr_flow)( 803) | #0:SETUP_EV : (12598589641120100423@10.151.3.14) 10:14:51.604 10.151.3.14 local0.notice [S=1137] [SID=58c3aa:28:13] ( lgr_flow)( 804) | #0:FWD_CALL_EV (send) : (12598589641120100423@10.151.3.14) 10:14:51.605 10.151.3.14 local0.notice [S=1138] [SID=58c3aa:28:13] ( lgr_flow)( 805) | | (#21):FWD_CALL_EV:(12598589641120100423@10.151.3.14) 10:14:51.606 10.151.3.14 local0.notice [S=1139] [SID=58c3aa:28:13] ( lgr_flow)( 806) | | | (#19)SIPStackSession <- (#0)FXSEndPoint: FWD_CALL_EV (12598589641120100423@10.151.3.14) 10:14:51.608 10.151.3.14 local0.notice [S=1140] [SID=58c3aa:28:13] ( sip_stack)( 807) New SIPMessage created - (#85) 10:14:51.609 10.151.3.14 local0.notice [S=1141] [SID=58c3aa:28:13] ( lgr_flow)( 808) | |(SIPTU(#4661)FORWARD_REQ State:Invited(12598589641120100423@10.151.3.14) 10:14:51.609 10.151.3.14 local0.notice [S=1142] [SID=58c3aa:28:13] ( sip_stack)( 809) SIPCall(#4661) changes state from Invited to Disconnected 10:14:51.610 10.151.3.14 local0.notice [S=1143] [SID=58c3aa:28:13] ( lgr_stack)( 810) [ManSet: 1, Rule: 0] - Executed on msgId:85 (Condition was True) 10:14:51.611 10.151.3.14 local0.notice [S=1144] [SID=58c3aa:28:13] ( sip_stack)( 811) UdpRtxMngr::Transmit 302 Response 1 INVITE Rtx Left: 6 Dest: 10.151.3.14:5060 CallID: (12598589641120100423@10.151.3.14) 10:14:51.612 10.151.3.14 local0.notice [S=1145] [SID=58c3aa:28:13] ( sip_stack)( 812) ---- Outgoing SIP Message to 10.151.3.14:5060 from SIPInterface #1 (GW) UDP TO(#1) ---- 10:14:51.614 10.151.3.14 local0.notice [S=1146] [SID=58c3aa:28:13] SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1519982499 From: "05333096548" ;tag=1c646854607 To: ;tag=1c172801070 Call-ID: 12598589641120100423@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 10:14:51.614 10.151.3.14 local0.notice [S=1147] [SID=58c3aa:28:13] ( lgr_flow)( 813) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:51.615 10.151.3.14 local0.notice [S=1148] [SID=58c3aa:28:13] ( lgr_stk_ses)( 814) SESSION (#19) SendToCall - event: RELEASE_EV m_Call(#21) 10:14:51.616 10.151.3.14 local0.notice [S=1149] [SID=58c3aa:28:13] ( lgr_flow)( 815) (#252)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:51.617 10.151.3.14 local0.notice [S=1150] [SID=58c3aa:28:13] ( lgr_flow)( 816) | | (#21):RELEASE_EV:(12598589641120100423@10.151.3.14) 10:14:51.618 10.151.3.14 local0.notice [S=1151] [SID=58c3aa:28:13] ( lgr_flow)( 817) | | (#21):Call changing states from:InitiatedState_IP2Tel to:DisconnectingState 10:14:51.620 10.151.3.14 local1.info [S=9] |CALL_END |0 |58c3aa:28:13 |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 |12598589641120100423@10.151.3.14 |00:04:23.433 UTC Fri Jan 01 2010 | |00:04:23.448 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 |6060 | | |FORWARDED | |DefaultSRD |GW |Caller_IP | |DefaultRealm |UDP |46 |24 |127 |127 |127 |127 |5060 |5070 |AUDIO | | |302 | 10:14:51.626 10.151.3.14 local1.info [S=10] |0 | |0 | |0 10:14:51.627 10.151.3.14 local0.notice [S=1152] [SID=58c3aa:28:13] ( lgr_flow)( 818) | | (#21):RELEASE_ACK_EV:(12598589641120100423@10.151.3.14) 10:14:51.629 10.151.3.14 local0.notice [S=1153] [SID=58c3aa:28:13] ( lgr_flow)( 819) | | (#21):Call will not free media resources since Call is forwarded 10:14:51.629 10.151.3.14 local0.notice [S=1154] [SID=58c3aa:28:13] ( lgr_flow)( 820) | #0:RELEASE_EV RELEASE_BECAUSE_FORWARD : (12598589641120100423@10.151.3.14) 10:14:51.631 10.151.3.14 local0.notice [S=1155] [SID=58c3aa:28:13] ( lgr_flow)( 821) | | (#21):RELEASE_ACK_EV:(12598589641120100423@10.151.3.14) 10:14:51.632 10.151.3.14 local0.notice [S=1156] [SID=58c3aa:28:13] ( lgr_flow)( 822) | | | (#19)SIPStackSession <- (#0)ENDPOINT: RELEASE_ACK_EV (12598589641120100423@10.151.3.14) 10:14:51.633 10.151.3.14 local0.notice [S=1157] [SID=58c3aa:28:13] ( sip_stack)( 823) New SIPMessage created - (#83) 10:14:51.634 10.151.3.14 local0.notice [S=1158] [SID=58c3aa:28:13] ( lgr_stk_mngr)( 824) SIPAppMngr::FreeCallAPI - (#26) 10:14:51.636 10.151.3.14 local0.notice [S=1159] [SID=58c3aa:28:13] ( sip_stack)( 825) Setting ApplicationCall of AcSIPCall(#4661) to NULL 10:14:51.636 10.151.3.14 local0.notice [S=1160] [SID=58c3aa:28:13] ( sip_stack)( 826) SDPBodyMedia::Delete - id = (#450) 10:14:51.637 10.151.3.14 local0.notice [S=1161] [SID=58c3aa:28:13] ( sip_stack)( 827) Resource BufferSDPBody deleted - (#170) 10:14:51.639 10.151.3.14 local0.notice [S=1162] [SID=58c3aa:28:13] ( lgr_stk_mngr)( 828) Resource StackSession (#19) Deleted 10:14:51.639 10.151.3.14 local0.notice [S=1163] [SID=58c3aa:28:13] ( sip_stack)( 829) Resource SIPMessage deleted - (#83) 10:14:51.640 10.151.3.14 local0.notice [S=1164] [SID=58c3aa:28:13] ( lgr_call)( 830) (#21) CALL Deallocated. 10:14:51.641 10.151.3.14 local0.notice [S=1165] [SID=58c3aa:28:13] ( lgr_call)( 831) delete GW call current active is: 1 10:14:51.642 10.151.3.14 local0.notice [S=1166] [SID=58c3aa:28:13] ( lgr_flow)( 832) EndPoint::MediaResourceList Perform FORCE Deallocation of port: Realm:1 port:0 current allocations:6060 10:14:51.642 10.151.3.14 local0.notice [S=1167] [SID=58c3aa:28:13] ( lgr_psbrdif)( 833) #0:cpDigitMapHndlr_Stop - Stopped (0) 10:14:51.644 10.151.3.14 local0.notice [S=1168] [SID=58c3aa:28:13] ( lgr_veint)( 834) #0:CloseChannel: ChannelNum=0 10:14:51.644 10.151.3.14 local0.notice [S=1169] [SID=58c3aa:28:14] ( lgr_flow)( 835) (#5340) gwSession Allocated. Handle:12DA1988; Global session ID: 66b8e00f14fd9c95 10:14:51.645 10.151.3.14 local0.notice [S=1170] [SID=58c3aa:28:14] ( lgr_veint)( 836) Open channel: IsVoiceOn: True, IsT38On: False, IsVbdOn: False 10:14:51.646 10.151.3.14 local0.notice [S=1171] [SID=58c3aa:28:14] ( lgr_veint)( 837) #0:OpenChannel:on Trunk -1 BChannel:0 CID=0 with VoiceCoder: g711Alaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED 10:14:51.647 10.151.3.14 local0.notice [S=1172] [SID=58c3aa:28:14] ( lgr_veint)( 838) #0:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 10:14:51.650 10.151.3.14 local0.notice [S=1173] [SID=58c3aa:28:14] ( lgr_veint)( 839) RFC2833RTPPayloadType: Rx=96 Tx=96 10:14:51.655 10.151.3.14 local0.notice [S=1174] [SID=58c3aa:28:14] ( lgr_veint)( 840) OpenChannel, CoderType = 0, Interval = 3, M = 1 10:14:51.655 10.151.3.14 local0.notice [S=1175] [SID=58c3aa:28:14] ( lgr_veint)( 841) OpenChannel, RTPMode = 1, UniDirectionalRTP = 0 10:14:51.656 10.151.3.14 local0.notice [S=1176] [SID=58c3aa:28:14] ( lgr_veint)( 842) ConfigureVbdAndT38:FAXTransportType 1VxxTransportType 0 T38Version:False FaxBypassPT:102 10:14:51.657 10.151.3.14 local0.notice [S=1177] [SID=58c3aa:28:14] ( lgr_veint)( 843) #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 10:14:51.657 10.151.3.14 local0.notice [S=1178] [SID=58c3aa:28:14] ( lgr_veint)( 844) #0:FAXTransportType = 1 10:14:51.659 10.151.3.14 local0.notice [S=1179] [SID=58c3aa:28:14] ( lgr_veint)( 845) SetDetectorsField :[ SIT] IPSide:[] SignalingMask=0xA1 10:14:51.661 10.151.3.14 local0.notice [S=1180] [SID=58c3aa:28:14] ( lgr_veint)( 846) #0:Channel will be open WITH DSP 10:14:51.663 10.151.3.14 local0.notice [S=1181] [SID=58c3aa:28:14] ( sip_stack)( 847) Resource SIPMessage deleted - (#85) 10:14:51.663 10.151.3.14 local0.notice [S=1182] [SID=58c3aa:28:14] ( lgr_stk_ses)( 848) SIPBaseStackSession(#19)::ProcessQueuedEvents: StackSession is released. Stopping iteration. 10:14:51.663 10.151.3.14 local0.notice [S=1183] [SID=58c3aa:28:14] ( sip_stack)( 849) SDPBody::Delete - id = (#48) 10:14:51.664 10.151.3.14 local0.notice [S=1184] [SID=58c3aa:28:14] ( sip_stack)( 850) SDPBodyMedia::Delete - id = (#453) 10:14:51.664 10.151.3.14 local0.notice [S=1185] [SID=58c3aa:28:14] ( sip_stack)( 851) Resource SIPMessage deleted - (#88) 10:14:51.665 10.151.3.14 local0.notice [S=1186] [SID=58c3aa:28:13] ( lgr_flow)( 852) (#0)IDSMngr <- (#0): IDSReportEvent 10:14:51.666 10.151.3.14 local0.notice [S=1187] [SID=58c3aa:28:12] ( sip_stack)( 853) ---- Incoming SIP Message from 10.151.3.14:5070 to SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:51.667 10.151.3.14 local0.notice [S=1188] [SID=58c3aa:28:12] SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1519982499 From: "05333096548" ;tag=1c646854607 To: Call-ID: 12598589641120100423@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 10:14:51.668 10.151.3.14 local0.notice [S=1189] [SID=58c3aa:28:12] ( sip_stack)( 854) New SIPMessage created - (#86) 10:14:51.669 10.151.3.14 local0.notice [S=1190] [SID=58c3aa:28:12] ( sip_stack)( 855) UdpRtxMngr::Remove 1 INVITE 10:14:51.670 10.151.3.14 local0.notice [S=1191] [SID=58c3aa:28:12] ( lgr_flow)( 856) | |(SIPTU(#4662)100 State:Inviting(12598589641120100423@10.151.3.14) 10:14:51.670 10.151.3.14 local0.notice [S=1192] [SID=58c3aa:28:12] ( sip_stack)( 857) SIPCall(#4662) changes state from Inviting to Proceeding 10:14:51.671 10.151.3.14 local0.notice [S=1193] [SID=58c3aa:28:12] ( sip_stack)( 858) Resource SIPMessage deleted - (#86) 10:14:51.672 10.151.3.14 local0.notice [S=1194] [SID=58c3aa:28:11] ( lgr_flow)( 859) (#5343) gwSession Deallocated. 10:14:51.674 10.151.3.14 local0.notice [S=1195] [SID=58c3aa:28:12] ( sip_stack)( 860) ---- Incoming SIP Message from 10.151.3.14:5070 to SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:51.675 10.151.3.14 local0.notice [S=1196] [SID=58c3aa:28:12] SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1519982499 From: "05333096548" ;tag=1c646854607 To: ;tag=1c172801070 Call-ID: 12598589641120100423@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 10:14:51.681 10.151.3.14 local0.notice [S=1197] [SID=58c3aa:28:12] ( sip_stack)( 861) New SIPMessage created - (#84) 10:14:51.682 10.151.3.14 local0.notice [S=1198] [SID=58c3aa:28:12] ( lgr_flow)( 862) | |(SIPTU(#4662)302 State:Proceeding(12598589641120100423@10.151.3.14) 10:14:51.684 10.151.3.14 local0.notice [S=1199] [SID=58c3aa:28:12] ( sip_stack)( 863) New SIPMessage created - (#82) 10:14:51.684 10.151.3.14 local0.notice [S=1200] [SID=58c3aa:28:12] ( sip_stack)( 864) ---- Outgoing SIP Message to 10.151.3.14:5070 from SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:51.686 10.151.3.14 local0.notice [S=1201] [SID=58c3aa:28:12] ACK sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1519982499 Max-Forwards: 70 From: "05333096548" ;tag=1c646854607 To: ;tag=1c172801070 Call-ID: 12598589641120100423@10.151.3.14 CSeq: 1 ACK Contact: User-Agent: M500/v.7.20A.154.052 Content-Length: 0 10:14:51.686 10.151.3.14 local0.notice [S=1202] [SID=58c3aa:28:12] ( lgr_flow)( 865) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:51.687 10.151.3.14 local0.notice [S=1203] [SID=58c3aa:28:12] ( sip_stack)( 866) Resource SIPMessage deleted - (#82) 10:14:51.689 10.151.3.14 local0.notice [S=1204] [SID=58c3aa:28:12] ( lgr_flow)( 867) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:51.689 10.151.3.14 local0.notice [S=1205] [SID=58c3aa:28:12] ( sip_stack)( 868) SIPCall(#4662) changes state from Proceeding to Disconnected 10:14:51.690 10.151.3.14 local0.notice [S=1206] [SID=58c3aa:28:12] ( lgr_flow)( 869) | | | (#4055)SIPSBCCallLeg <- (#4055)AcSBCCallAPI: SBCDisconnectData 10:14:51.691 10.151.3.14 local0.notice [S=1207] [SID=58c3aa:28:12] ( lgr_stk_ses)( 870) SESSION (#4055) SendToCall - event: RELEASE_EV m_Call(#22) 10:14:51.692 10.151.3.14 local0.notice [S=1208] [SID=58c3aa:28:12] ( lgr_flow)( 871) | | (#22)SBCVoiceCall <- (#4055)SIPSBCCallLeg: RELEASE_EV 10:14:51.693 10.151.3.14 local0.notice [S=1209] [SID=58c3aa:28:12] ( lgr_flow)( 872) | | (#22) SBCCall changing states from:InitiatedState to:DisconnectingState 10:14:51.694 10.151.3.14 local0.notice [S=1210] [SID=58c3aa:28:12] ( lgr_flow)( 873) | (#128)SBCCallPlacementFeature <- (#22)SBCVoiceCall: RELEASE_EV 10:14:51.694 10.151.3.14 local0.notice [S=1211] [SID=58c3aa:28:12] ( lgr_flow)( 874) (#118)SBCRoutesIterator::Change State From: AlternativeRouting To : Alternative3xx 10:14:51.695 10.151.3.14 local0.notice [S=1212] [SID=58c3aa:28:12] ( lgr_flow)( 875) (#118)SBCRoutesIterator::Change State From: Alternative3xx To : InitialCSRRouting 10:14:51.696 10.151.3.14 local0.notice [S=1213] [SID=58c3aa:28:12] ( lgr_flow)( 876) (#118)SBCRoutesIterator::Change State From: InitialCSRRouting To : InitialRouting 10:14:51.697 10.151.3.14 local0.notice [S=1214] [SID=58c3aa:28:12] ( lgr_flow)( 877) (#118)SBCRoutesIterator::Change State From: InitialRouting To : AlternativeRouting 10:14:51.699 10.151.3.14 local0.notice [S=1215] [SID=58c3aa:28:12] ( lgr_flow)( 878) (#-1)SBCAdmissionControlMngr <- (#118)SBCRoutesIterator: SBC_ADMIT_DIALOGS_EV 10:14:51.699 10.151.3.14 local0.notice [S=1216] [SID=58c3aa:28:12] ( lgr_sbc)( 879) 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 10:14:51.700 10.151.3.14 local0.notice [S=1217] [SID=58c3aa:28:12] ( media_service)( 880) ServicesMngr: Allocate SBC leg. current active: 2 and max is: 10 10:14:51.701 10.151.3.14 local0.notice [S=1218] [SID=58c3aa:28:12] ( lgr_flow)( 881) (#118)SBCRoutesIterator::Next route found: Rule #0, Route by: IPGroup , IP Group ID: 0 (Caller Addr), Live:True 10:14:51.702 10.151.3.14 local0.notice [S=1219] [SID=58c3aa:28:12] ( lgr_sbc)( 882) Routing Succeeded -IP2IPRouting Rule #0 10:14:51.705 10.151.3.14 local0.notice [S=1220] [SID=58c3aa:28:12] ( sip_stack)( 883) Setting ApplicationCall of AcSIPCall(#4662) to NULL 10:14:51.706 10.151.3.14 local0.notice [S=1221] [SID=58c3aa:28:12] ( lgr_flow)( 884) | (#128)SBCCallPlacementFeature <- (#118)SBCRoutesIterator: SBC_ROUTING_DONE_EV 10:14:51.710 10.151.3.14 local0.notice [S=1222] [SID=58c3aa:28:12] ( lgr_call)( 885) (#20) CALL Allocated. 10:14:51.712 10.151.3.14 local0.notice [S=1223] [SID=58c3aa:28:12] ( lgr_flow)( 886) | | (#20)SBCVoiceCall <- (#128)SBCCallPlacementFeature: NEW_CALL_EV 10:14:51.712 10.151.3.14 local0.notice [S=1224] [SID=58c3aa:28:12] ( lgr_flow)( 887) | | (#20) SBCCall changing states from:IdleState to:NewCallState 10:14:51.714 10.151.3.14 local0.notice [S=1225] [SID=58c3aa:28:12] ( lgr_call)( 888) 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 10:14:51.714 10.151.3.14 local0.notice [S=1226] [SID=58c3aa:28:12] ( lgr_stk_mngr)( 889) (#4054)SIPSBCCallLeg Allocated. 10:14:51.715 10.151.3.14 local0.notice [S=1227] [SID=58c3aa:28:12] ( lgr_flow)( 890) | | | (#4054)SIPSBCCallLeg <- (#20)SBCVoiceCall: NEW_CALL_EV 10:14:51.716 10.151.3.14 local0.notice [S=1228] [SID=58c3aa:28:12] ( lgr_flow)( 891) (#141) MediaMetaDataList Allocated. 10:14:51.716 10.151.3.14 local0.notice [S=1229] [SID=58c3aa:28:12] ( lgr_flow)( 892) | | (#20)SBCVoiceCall <- (#128)SBCCallPlacementFeature: SETUP_EV 10:14:51.718 10.151.3.14 local0.notice [S=1230] [SID=58c3aa:28:12] ( sip_stack)( 893) SDPBody::New - id = (#47) 10:14:51.719 10.151.3.14 local0.notice [S=1231] [SID=58c3aa:28:12] ( sip_stack)( 894) SDPBodyMedia::New - id = (#449) 10:14:51.720 10.151.3.14 local0.notice [S=1232] [SID=58c3aa:28:12] ( lgr_flow)( 895) | | (#20) SBCCall changing states from:NewCallState to:InitiatedState 10:14:51.721 10.151.3.14 local0.notice [S=1233] [SID=58c3aa:28:12] ( lgr_flow)( 896) | | | (#4054)SIPSBCCallLeg <- (#20)SBCVoiceCall: SETUP_EV 10:14:51.721 10.151.3.14 local0.notice [S=1234] [SID=58c3aa:28:12] ( lgr_stk_ses)( 897) SIPSBCCallLeg(#4054) - Route by dest IPGroup: #0 10:14:51.722 10.151.3.14 local0.notice [S=1235] [SID=58c3aa:28:12] ( lgr_flow)( 898) | | new AcSIPCall created - (#4660) 10:14:51.724 10.151.3.14 local0.notice [S=1236] [SID=58c3aa:28:12] ( lgr_stk_ses)( 899) SBCOfferAnswerMngr(#4054) changes state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERING 10:14:51.724 10.151.3.14 local0.notice [S=1237] [SID=58c3aa:28:12] ( media_service)( 900) (#4) MediaResourceAdapter Allocated. OrigSide TEL 10:14:51.725 10.151.3.14 local0.notice [S=1238] [SID=58c3aa:28:12] ( lgr_stk_ses)( 901) SBCSDPDTMFFeature::UpdateMediaDTMFConfig: m_RFC=0 m_Alternative=0 m_PeerRFC=0 m_PeerAlternative=0 m_SendMultipleDTMFMethods=False m_PeerSendMultipleDTMFMethods=False 10:14:51.726 10.151.3.14 local0.notice [S=1239] [SID=58c3aa:28:12] ( lgr_stk_ses)( 902) SBCSDPANATFeature::UpdateANATConfig - Behavior isIP_VERSION_ONLY_IPV4 10:14:51.726 10.151.3.14 local0.notice [S=1240] [SID=58c3aa:28:12] ( media_service)( 903) (#14) RTPStreamResource Allocated. Sibling ID: (#-1) 10:14:51.727 10.151.3.14 local0.notice [S=1241] [SID=58c3aa:28:12] ( media_service)( 904) (#4) ChannelResource Allocated. 10:14:51.729 10.151.3.14 local0.notice [S=1242] [SID=58c3aa:28:12] ( media_service)( 905) (#4) ChannelResource belongs to call #20 10:14:51.730 10.151.3.14 local0.notice [S=1243] [SID=58c3aa:28:12] ( lgr_flow)( 906) (#4)ChannelResource::AllocateMediaIpPorts RealmIndex(0) port(6070) Allocated. 10:14:51.738 10.151.3.14 local0.notice [S=1244] [SID=58c3aa:28:12] ( lgr_flow)( 907) #MediaResourcesConnector::AllocateMediaResources 10:14:51.739 10.151.3.14 local0.notice [S=1245] [SID=58c3aa:28:12] ( media_connect)( 908) ConnectionData::CalculateResourcesForRTPForwarding Leading:NONE Opposite:NONE MediationLevel:RTP 10:14:51.739 10.151.3.14 local0.notice [S=1246] [SID=58c3aa:28:12] ( lgr_flow)( 909) #MediaResourcesConnector::AllocateMediaResources 10:14:51.740 10.151.3.14 local0.notice [S=1247] [SID=58c3aa:28:12] ( media_connect)( 910) ConnectionData::CalculateResourcesForRTPForwarding Leading:NONE Opposite:NONE MediationLevel:RTP 10:14:51.741 10.151.3.14 local0.notice [S=1248] [SID=58c3aa:28:12] ( lgr_stk_ses)( 911) DirectMediaDB::StoreSDP added DirectMediaData to Database IP:10.151.3.14 Port:6070 SDP:(#173) 10:14:51.742 10.151.3.14 local0.notice [S=1249] [SID=58c3aa:28:12] ( lgr_flow)( 912) (#140) MediaMetaDataList Allocated. 10:14:51.744 10.151.3.14 local0.notice [S=1250] [SID=58c3aa:28:12] ( lgr_stk_mngr)( 913) New BufferSDPBody created - (#169) 10:14:51.744 10.151.3.14 local0.notice [S=1251] [SID=58c3aa:28:12] ( sip_stack)( 914) SDPBodyMedia::New - id = (#448) 10:14:51.745 10.151.3.14 local0.notice [S=1252] [SID=58c3aa:28:12] ( sip_sbc)( 915) SIPSBCMngr::GetStackEP - No user is found in DB 10:14:51.745 10.151.3.14 local0.notice [S=1253] [SID=58c3aa:28:12] ( lgr_flow)( 916) | |(SIPTU(#4660)SETUP_REQ State:Idle() 10:14:51.746 10.151.3.14 local0.notice [S=1254] [SID=58c3aa:28:12] ( sip_stack)( 917) SIPCall(#4660) changes state from Idle to Inviting 10:14:51.748 10.151.3.14 local0.notice [S=1255] [SID=58c3aa:28:12] ( sip_stack)( 918) TU(#4660) SIPSessionTimer::FillSTRequestData - Session-Timer mode: TRANSPARENT 10:14:51.749 10.151.3.14 local0.notice [S=1256] [SID=58c3aa:28:12] ( lgr_stack)( 919) [ManSet: 1, Rule: 2] - Executed on msgId:90 (Condition was True) 10:14:51.750 10.151.3.14 local0.notice [S=1257] [SID=58c3aa:28:12] ( sip_stack)( 920) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 6 Dest: 10.147.253.165:5060 CallID: (12598589641120100423@10.151.3.14) 10:14:51.750 10.151.3.14 local0.notice [S=1258] [SID=58c3aa:28:12] ( sip_stack)( 921) ---- Outgoing SIP Message to 10.147.253.165:5060 from SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:51.751 10.151.3.14 local0.notice [S=1259] [SID=58c3aa:28:12] INVITE sip:05352038980@10.147.253.165;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac606011495 Max-Forwards: 69 From: "05333096548" ;tag=1c1085994311 To: Call-ID: 12598589641120100423@10.151.3.14 CSeq: 1 INVITE Contact: Supported: replaces,sdp-anat Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE Diversion: ;reason=unconditional;counter=1 User-Agent: M500/v.7.20A.154.052 P-Asserted-Identity: Content-Type: application/sdp Content-Length: 209 v=0 o=- 123443407 1256677808 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 6070 RTP/AVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv 10:14:51.752 10.151.3.14 local0.notice [S=1260] [SID=58c3aa:28:12] ( lgr_flow)( 922) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:51.753 10.151.3.14 local0.notice [S=1261] [SID=58c3aa:28:12] ( lgr_flow)( 923) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:51.754 10.151.3.14 local0.notice [S=1262] [SID=58c3aa:28:12] ( sip_stack)( 924) SDPBody::Delete - id = (#47) 10:14:51.755 10.151.3.14 local0.notice [S=1263] [SID=58c3aa:28:12] ( sip_stack)( 925) SDPBodyMedia::Delete - id = (#449) 10:14:51.756 10.151.3.14 local0.notice [S=1264] [SID=58c3aa:28:12] ( lgr_flow)( 926) | | (#22)SBCVoiceCall <- (#128)SBCCallPlacementFeature: RELEASE_ACK_EV 10:14:51.757 10.151.3.14 local0.notice [S=1265] [SID=58c3aa:28:12] ( lgr_flow)( 927) | | (#22) SBCCall changing states from:DisconnectingState to:DisconnectedState 10:14:51.760 10.151.3.14 local0.notice [S=1266] [SID=58c3aa:28:12] ( lgr_flow)( 928) | | | (#4055)SIPSBCCallLeg <- (#22)SBCVoiceCall: RELEASE_ACK_EV 10:14:51.761 10.151.3.14 local0.notice [S=1267] [SID=58c3aa:28:12] ( sip_stack)( 929) New SIPMessage created - (#80) 10:14:51.762 10.151.3.14 local0.notice [S=1268] [SID=58c3aa:28:12] ( sip_stack)( 930) AcSBCCallAPI::DisconnectAck: No SIPCall 10:14:51.766 10.151.3.14 local0.notice [S=1269] [SID=58c3aa:28:12] ( lgr_stk_ses)( 931) SIPSBCCallLeg(#4055) Deallocated 10:14:51.766 10.151.3.14 local0.notice [S=1270] [SID=58c3aa:28:12] ( sip_stack)( 932) SDPBodyMedia::Delete - id = (#454) 10:14:51.768 10.151.3.14 local0.notice [S=1271] [SID=58c3aa:28:12] ( sip_stack)( 933) Resource BufferSDPBody deleted - (#172) 10:14:51.769 10.151.3.14 local0.notice [S=1272] [SID=58c3aa:28:12] ( lgr_flow)( 934) (#142) MediaMetaDataList Deallocated. 10:14:51.770 10.151.3.14 local0.notice [S=1273] [SID=58c3aa:28:12] ( lgr_stk_ses)( 935) DirectMediaDB::RemoveData Removed DirectMediaData from Database IP:10.151.3.14 Port:6050 SDP:(#173) 10:14:51.771 10.151.3.14 local0.notice [S=1274] [SID=58c3aa:28:12] ( sip_stack)( 936) Resource SIPMessage deleted - (#80) 10:14:51.772 10.151.3.14 local1.info [S=11] |CALL_END |SBC |12598589641120100423@10.151.3.14 |58c3aa:28:12 |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:04:23.373 UTC Fri Jan 01 2010 | |00:04:23.527 UTC Fri Jan 01 2010 |-1 | | |24 |GW |DefaultSRD |SBC |GW_IP |Emre |DefaultRealm |no |302 |SIP ;cause=302 ;text="302 |05333096548 | |Normal |2 10:14:51.773 10.151.3.14 local0.notice [S=1275] [SID=58c3aa:28:12] ( media_service)( 937) (#5) MediaResourceAdapter Deallocated. 10:14:51.774 10.151.3.14 local0.notice [S=1276] [SID=58c3aa:28:12] ( lgr_flow)( 938) (#15)RTPStreamResource::DeAllocateResource, CID -100 Detached. 10:14:51.775 10.151.3.14 local0.notice [S=1277] [SID=58c3aa:28:12] ( lgr_flow)( 939) (#5)ChannelResource::DeAllocateMediaIpPorts RealmIndex(0) port(6050) IPV4IF(0) Deallocated. 10:14:51.775 10.151.3.14 local0.notice [S=1278] [SID=58c3aa:28:12] ( media_service)( 940) (#5) ChannelResource Deallocated. 10:14:51.776 10.151.3.14 local0.notice [S=1279] [SID=58c3aa:28:12] ( media_service)( 941) (#15) RTPStreamResource Deallocated. 10:14:51.777 10.151.3.14 local0.notice [S=1280] [SID=58c3aa:28:12] ( lgr_gwapp_ha)( 942) (#22)SBCVoiceCall Insert Delete Resource event 10:14:51.779 10.151.3.14 local0.notice [S=1281] [SID=58c3aa:28:13] ( sip_stack)( 943) ---- Incoming SIP Message from 10.151.3.14:5060 to SIPInterface #1 (GW) UDP TO(#1) ---- 10:14:51.779 10.151.3.14 local0.notice [S=1282] [SID=58c3aa:28:13] ACK sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.14:5060;branch=z9hG4bKac1519982499 Max-Forwards: 70 From: "05333096548" ;tag=1c646854607 To: ;tag=1c172801070 Call-ID: 12598589641120100423@10.151.3.14 CSeq: 1 ACK Contact: User-Agent: M500/v.7.20A.154.052 Content-Length: 0 10:14:51.780 10.151.3.14 local0.notice [S=1283] [SID=58c3aa:28:13] ( sip_stack)( 944) New SIPMessage created - (#81) 10:14:51.781 10.151.3.14 local0.notice [S=1284] [SID=58c3aa:28:13] ( sip_stack)( 945) UdpRtxMngr::Remove 302 Response 1 INVITE 10:14:51.782 10.151.3.14 local0.notice [S=1285] [SID=58c3aa:28:13] ( lgr_flow)( 946) | |(SIPTU(#4661)ACK State:Disconnected(12598589641120100423@10.151.3.14) 10:14:51.783 10.151.3.14 local0.notice [S=1286] [SID=58c3aa:28:13] ( sip_stack)( 947) Resource SIPMessage deleted - (#81) 10:14:51.784 10.151.3.14 local0.notice [S=1287] [SID=58c3aa:28:12] ( lgr_sbc)( 948) 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 10:14:51.785 10.151.3.14 local0.notice [S=1288] [SID=58c3aa:28:12] ( media_service)( 949) ServicesMngr: Deallocate SBC leg. current active: 3 and max is: 10 10:14:51.788 10.151.3.14 local0.notice [S=1289] [SID=58c3aa:28:12] ( lgr_call)( 950) (#22) CALL Deallocated. 10:14:51.899 10.151.3.14 local0.notice [S=1290] [SID=58c3aa:28:12] ( sip_stack)( 951) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 5 Dest: 10.147.253.165:5060 CallID: (12598589641120100423@10.151.3.14) 10:14:51.900 10.151.3.14 local0.notice [S=1291] [SID=58c3aa:28:12] ( sip_stack)( 952) SIPTransaction(#981)::SendMsgBuffer - Resending last message 10:14:51.900 10.151.3.14 local0.notice [S=1292] [SID=58c3aa:28:12] ( lgr_flow)( 953) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:51.901 10.151.3.14 local0.notice [S=1293] [SID=58c3aa:28:12] ( lgr_flow)( 954) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:52.912 10.151.3.14 local0.notice [S=1294] [SID=58c3aa:28:12] ( sip_stack)( 955) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 4 Dest: 10.147.253.165:5060 CallID: (12598589641120100423@10.151.3.14) 10:14:52.912 10.151.3.14 local0.notice [S=1295] [SID=58c3aa:28:12] ( sip_stack)( 956) SIPTransaction(#981)::SendMsgBuffer - Resending last message 10:14:52.914 10.151.3.14 local0.notice [S=1296] [SID=58c3aa:28:12] ( lgr_flow)( 957) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:52.914 10.151.3.14 local0.notice [S=1297] [SID=58c3aa:28:12] ( lgr_flow)( 958) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:54.899 10.151.3.14 local0.notice [S=1298] [SID=58c3aa:28:12] ( sip_stack)( 959) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 3 Dest: 10.147.253.165:5060 CallID: (12598589641120100423@10.151.3.14) 10:14:54.900 10.151.3.14 local0.notice [S=1299] [SID=58c3aa:28:12] ( sip_stack)( 960) SIPTransaction(#981)::SendMsgBuffer - Resending last message 10:14:54.901 10.151.3.14 local0.notice [S=1300] [SID=58c3aa:28:12] ( lgr_flow)( 961) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:54.901 10.151.3.14 local0.notice [S=1301] [SID=58c3aa:28:12] ( lgr_flow)( 962) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:56.359 10.151.3.14 local0.notice [S=1302] [SID=58c3aa:28:12] ( sip_stack)( 963) SIPCall(#4662) changes state from Disconnected to Idle 10:14:56.360 10.151.3.14 local0.notice [S=1303] [SID=58c3aa:28:12] ( lgr_flow)( 964) | | ACSIPStack::ReturnTU - (#4662) 10:14:56.419 10.151.3.14 local0.notice [S=1304] [SID=58c3aa:28:13] ( sip_stack)( 965) SIPCall(#4661) changes state from Disconnected to Idle 10:14:56.420 10.151.3.14 local0.notice [S=1305] [SID=58c3aa:28:13] ( lgr_flow)( 966) | | ACSIPStack::ReturnTU - (#4661) 10:14:56.420 10.151.3.14 local0.notice [S=1306] [SID=58c3aa:28:13] ( lgr_flow)( 967) (#5341) gwSession Deallocated. 10:14:57.760 10.151.3.14 local0.notice [S=1307] [SID=58c3aa:28:12] ( sip_stack)( 968) ---- Incoming SIP Message from 10.151.3.13:5060 to SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:57.760 10.151.3.14 local0.notice [S=1308] [SID=58c3aa:28:12] CANCEL sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.13:5060;branch=z9hG4bK-524287-1---ebb395234ac7652e;rport Max-Forwards: 70 To: From: "05333096548";tag=ce219c37 Call-ID: 88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q CSeq: 1 CANCEL User-Agent: X-Lite release 5.0.3 stamp 88254 Content-Length: 0 10:14:57.762 10.151.3.14 local0.notice [S=1309] [SID=58c3aa:28:12] ( sip_stack)( 969) New SIPMessage created - (#79) 10:14:57.763 10.151.3.14 local0.notice [S=1310] [SID=58c3aa:28:12] ( lgr_flow)( 970) | |(SIPTU(#4663)CANCEL State:Invited(88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q) 10:14:57.764 10.151.3.14 local0.notice [S=1311] [SID=58c3aa:28:12] ( sip_stack)( 971) SIPCall(#4663) changes state from Invited to Disconnected 10:14:57.765 10.151.3.14 local0.notice [S=1312] [SID=58c3aa:28:12] ( sip_stack)( 972) New SIPMessage created - (#78) 10:14:57.765 10.151.3.14 local0.notice [S=1313] [SID=58c3aa:28:12] ( sip_stack)( 973) ---- Outgoing SIP Message to 10.151.3.13:5060 from SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:57.766 10.151.3.14 local0.notice [S=1314] [SID=58c3aa:28:12] 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---ebb395234ac7652e From: "05333096548" ;tag=ce219c37 To: ;tag=1c686575526 Call-ID: 88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q CSeq: 1 CANCEL Contact: Server: M500/v.7.20A.154.052 Content-Length: 0 10:14:57.767 10.151.3.14 local0.notice [S=1315] [SID=58c3aa:28:12] ( lgr_flow)( 974) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:57.768 10.151.3.14 local0.notice [S=1316] [SID=58c3aa:28:12] ( sip_stack)( 975) Resource SIPMessage deleted - (#78) 10:14:57.769 10.151.3.14 local0.notice [S=1317] [SID=58c3aa:28:12] ( lgr_flow)( 976) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:57.770 10.151.3.14 local0.notice [S=1318] [SID=58c3aa:28:12] ( sip_stack)( 977) New SIPMessage created - (#77) 10:14:57.770 10.151.3.14 local0.notice [S=1319] [SID=58c3aa:28:12] ( sip_stack)( 978) UdpRtxMngr::Transmit 487 Response 1 INVITE Rtx Left: 6 Dest: 10.151.3.13:5060 CallID: (88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q) 10:14:57.771 10.151.3.14 local0.notice [S=1320] [SID=58c3aa:28:12] ( sip_stack)( 979) ---- Outgoing SIP Message to 10.151.3.13:5060 from SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:57.772 10.151.3.14 local0.notice [S=1321] [SID=58c3aa:28:12] 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---ebb395234ac7652e From: "05333096548" ;tag=ce219c37 To: ;tag=1c686575526 Call-ID: 88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q CSeq: 1 INVITE Server: M500/v.7.20A.154.052 Reason: SIP ;cause=487 ;text="487 Request Terminated" Content-Length: 0 10:14:57.773 10.151.3.14 local0.notice [S=1322] [SID=58c3aa:28:12] ( lgr_flow)( 980) (#0)SIPSocketMngr <- (#0)UdpTransportObject: LBEventSendMsg 10:14:57.774 10.151.3.14 local0.notice [S=1323] [SID=58c3aa:28:12] ( sip_stack)( 981) Resource SIPMessage deleted - (#77) 10:14:57.775 10.151.3.14 local0.notice [S=1324] [SID=58c3aa:28:12] ( lgr_flow)( 982) (#253)SIPSocketUDP <- (#0)SIPSocketMngr: LBEventSendMsg 10:14:57.776 10.151.3.14 local0.notice [S=1325] [SID=58c3aa:28:12] ( lgr_flow)( 983) | | | (#4056)SIPSBCCallLeg <- (#4056)AcSBCCallAPI: SBCDisconnectData 10:14:57.777 10.151.3.14 local0.notice [S=1326] [SID=58c3aa:28:12] ( lgr_stk_ses)( 984) SESSION (#4056) SendToCall - event: RELEASE_EV m_Call(#23) 10:14:57.777 10.151.3.14 local0.notice [S=1327] [SID=58c3aa:28:12] ( lgr_flow)( 985) | | (#23)SBCVoiceCall <- (#4056)SIPSBCCallLeg: RELEASE_EV 10:14:57.778 10.151.3.14 local0.notice [S=1328] [SID=58c3aa:28:12] ( lgr_flow)( 986) | | (#23) SBCCall changing states from:InitiatedState to:DisconnectingState 10:14:57.779 10.151.3.14 local0.notice [S=1329] [SID=58c3aa:28:12] ( lgr_flow)( 987) (#4056)SBCParticipantEndPoint <- (#23)SBCVoiceCall: RELEASE_EV 10:14:57.780 10.151.3.14 local0.notice [S=1330] [SID=58c3aa:28:12] ( lgr_flow)( 988) (#4056) SBCParticipantEndPoint changing states from:InitiatedState to:ReleaseingState 10:14:57.793 10.151.3.14 local0.notice [S=1331] [SID=58c3aa:28:12] ( lgr_flow)( 989) (#2037)SBCController <- (#4056)SBCParticipantEndPoint: RELEASE_EV 10:14:57.797 10.151.3.14 local0.notice [S=1332] [SID=58c3aa:28:12] ( lgr_flow)( 990) | | (#2037) SBCController changing states from:EstablishingState to:DisconnectingState 10:14:57.798 10.151.3.14 local0.notice [S=1333] [SID=58c3aa:28:12] ( lgr_flow)( 991) (#4057)SBCParticipantEndPoint <- (#2037)SBCController: RELEASE_EV 10:14:57.800 10.151.3.14 local0.notice [S=1334] [SID=58c3aa:28:12] ( lgr_flow)( 992) (#4057) SBCParticipantEndPoint changing states from:InitiatedState to:ReleaseingState 10:14:57.800 10.151.3.14 local0.notice [S=1335] [SID=58c3aa:28:12] ( lgr_flow)( 993) | (#128)SBCCallPlacementFeature <- (#4057)SBCParticipantEndPoint: RELEASE_EV 10:14:57.801 10.151.3.14 local0.notice [S=1336] [SID=58c3aa:28:12] ( lgr_flow)( 994) (#128) SBCCallPlacementFeature changing states from: Initiated to: Releasing 10:14:57.802 10.151.3.14 local0.notice [S=1337] [SID=58c3aa:28:12] ( lgr_flow)( 995) | | (#20)SBCVoiceCall <- (#128)SBCCallPlacementFeature: RELEASE_EV 10:14:57.804 10.151.3.14 local0.notice [S=1338] [SID=58c3aa:28:12] ( lgr_flow)( 996) | | (#20) SBCCall changing states from:InitiatedState to:DisconnectingState 10:14:57.804 10.151.3.14 local0.notice [S=1339] [SID=58c3aa:28:12] ( lgr_flow)( 997) | | | (#4054)SIPSBCCallLeg <- (#20)SBCVoiceCall: RELEASE_EV 10:14:57.805 10.151.3.14 local0.notice [S=1340] [SID=58c3aa:28:12] ( lgr_flow)( 998) | |(SIPTU(#4660)DISCONNECT_REQ State:Inviting(12598589641120100423@10.151.3.14) 10:14:57.806 10.151.3.14 local0.notice [S=1341] [SID=58c3aa:28:12] ( sip_stack)( 999) SIPCall(#4660) changes state from Inviting to Cancelling 10:14:57.807 10.151.3.14 local0.notice [S=1342] [SID=58c3aa:28:12] ( sip_stack)( 1000) UdpRtxMngr::Remove 1 INVITE 10:14:57.811 10.151.3.14 local0.notice [S=1343] [SID=58c3aa:28:12] ( sip_stack)( 1001) Resource SIPMessage deleted - (#79) 10:14:57.811 10.151.3.14 local0.notice [S=1344] [SID=58c3aa:28:12] ( lgr_flow)( 1002) (#0)IDSMngr <- (#0): IDSReportEvent 10:14:57.812 10.151.3.14 local0.notice [S=1345] [SID=58c3aa:28:12] ( lgr_flow)( 1003) | | | (#4054)SIPSBCCallLeg <- (#4054)AcSBCCallAPI: SBCDisconnectAckData 10:14:57.815 10.151.3.14 local0.notice [S=1346] [SID=58c3aa:28:12] ( lgr_stk_ses)( 1004) SESSION (#4054) SendToCall - event: RELEASE_ACK_EV m_Call(#20) 10:14:57.817 10.151.3.14 local0.notice [S=1347] [SID=58c3aa:28:12] ( lgr_flow)( 1005) | | (#20)SBCVoiceCall <- (#4054)SIPSBCCallLeg: RELEASE_ACK_EV 10:14:57.817 10.151.3.14 local0.notice [S=1348] [SID=58c3aa:28:12] ( lgr_flow)( 1006) | | (#20) SBCCall changing states from:DisconnectingState to:DisconnectedState 10:14:57.821 10.151.3.14 local0.notice [S=1349] [SID=58c3aa:28:12] ( lgr_flow)( 1007) | (#128)SBCCallPlacementFeature <- (#20)SBCVoiceCall: RELEASE_ACK_EV 10:14:57.822 10.151.3.14 local0.notice [S=1350] [SID=58c3aa:28:12] ( lgr_flow)( 1008) (#4057)SBCParticipantEndPoint <- (#128)SBCCallPlacementFeature: RELEASE_ACK_EV 10:14:57.823 10.151.3.14 local0.notice [S=1351] [SID=58c3aa:28:12] ( lgr_flow)( 1009) (#4057) SBCParticipantEndPoint changing states from:ReleaseingState to:ReleasedState 10:14:57.826 10.151.3.14 local0.notice [S=1352] [SID=58c3aa:28:12] ( lgr_gwapp_ha)( 1010) (#4057)SBCParticipantEndPoint Insert Delete Resource event 10:14:57.826 10.151.3.14 local0.notice [S=1353] [SID=58c3aa:28:12] ( lgr_flow)( 1011) (#2037)SBCController <- (#4057)SBCParticipantEndPoint: RELEASE_ACK_EV 10:14:57.826 10.151.3.14 local0.notice [S=1354] [SID=58c3aa:28:12] ( lgr_flow)( 1012) | | (#2037) SBCController changing states from:DisconnectingState to:DisconnectedState 10:14:57.826 10.151.3.14 local0.notice [S=1355] [SID=58c3aa:28:12] ( media_connect)( 1013) (#3) MediaResourcesConnector Deallocated. 10:14:57.827 10.151.3.14 local0.notice [S=1356] [SID=58c3aa:28:12] ( lgr_gwapp_ha)( 1014) (#2037)SBCController Insert Delete Resource event 10:14:57.829 10.151.3.14 local0.notice [S=1357] [SID=58c3aa:28:12] ( lgr_flow)( 1015) (#4056)SBCParticipantEndPoint <- (#2037)SBCController: RELEASE_ACK_EV 10:14:57.829 10.151.3.14 local0.notice [S=1358] [SID=58c3aa:28:12] ( lgr_flow)( 1016) (#4056) SBCParticipantEndPoint changing states from:ReleaseingState to:ReleasedState 10:14:57.830 10.151.3.14 local0.notice [S=1359] [SID=58c3aa:28:12] ( lgr_gwapp_ha)( 1017) (#4056)SBCParticipantEndPoint Insert Delete Resource event 10:14:57.831 10.151.3.14 local0.notice [S=1360] [SID=58c3aa:28:12] ( lgr_flow)( 1018) | | (#23)SBCVoiceCall <- (#4056)SBCParticipantEndPoint: RELEASE_ACK_EV 10:14:57.832 10.151.3.14 local0.notice [S=1361] [SID=58c3aa:28:12] ( lgr_flow)( 1019) | | (#23) SBCCall changing states from:DisconnectingState to:DisconnectedState 10:14:57.833 10.151.3.14 local0.notice [S=1362] [SID=58c3aa:28:12] ( lgr_flow)( 1020) | | | (#4056)SIPSBCCallLeg <- (#23)SBCVoiceCall: RELEASE_ACK_EV 10:14:57.834 10.151.3.14 local0.notice [S=1363] [SID=58c3aa:28:12] ( sip_stack)( 1021) New SIPMessage created - (#76) 10:14:57.835 10.151.3.14 local0.notice [S=1364] [SID=58c3aa:28:12] ( lgr_flow)( 1022) | |(SIPTU(#4663)DISCONNECT_RESPONSE State:Disconnected(88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q) 10:14:57.836 10.151.3.14 local0.notice [S=1365] [SID=58c3aa:28:12] ( lgr_stk_ses)( 1023) SIPSBCCallLeg(#4056) Deallocated 10:14:57.839 10.151.3.14 local0.notice [S=1366] [SID=58c3aa:28:12] ( sip_stack)( 1024) Setting ApplicationCall of AcSIPCall(#4663) to NULL 10:14:57.840 10.151.3.14 local0.notice [S=1367] [SID=58c3aa:28:12] ( lgr_flow)( 1025) (#144) MediaMetaDataList Deallocated. 10:14:57.840 10.151.3.14 local0.notice [S=1368] [SID=58c3aa:28:12] ( sip_stack)( 1026) Resource SIPMessage deleted - (#76) 10:14:57.842 10.151.3.14 local1.info [S=12] |CALL_END |SBC |88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q |58c3aa:28:12 |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:04:23.368 UTC Fri Jan 01 2010 | |00:04:29.863 UTC Fri Jan 01 2010 |-1 | | |24 |Caller Addr |DefaultSRD |SBC |Caller_IP |Emre |DefaultRealm |no |CANCEL | |05333096548 | |Normal |1 10:14:57.843 10.151.3.14 local0.notice [S=1369] [SID=58c3aa:28:12] ( media_service)( 1027) (#6) MediaResourceAdapter Deallocated. 10:14:57.843 10.151.3.14 local0.notice [S=1370] [SID=58c3aa:28:12] ( lgr_flow)( 1028) (#16)RTPStreamResource::DeAllocateResource, CID -100 Detached. 10:14:57.844 10.151.3.14 local0.notice [S=1371] [SID=58c3aa:28:12] ( lgr_flow)( 1029) (#6)ChannelResource::DeAllocateMediaIpPorts RealmIndex(0) port(6040) IPV4IF(0) Deallocated. 10:14:57.849 10.151.3.14 local0.notice [S=1372] [SID=58c3aa:28:12] ( media_service)( 1030) (#6) ChannelResource Deallocated. 10:14:57.849 10.151.3.14 local0.notice [S=1373] [SID=58c3aa:28:12] ( media_service)( 1031) (#16) RTPStreamResource Deallocated. 10:14:57.850 10.151.3.14 local0.notice [S=1374] [SID=58c3aa:28:12] ( lgr_gwapp_ha)( 1032) (#23)SBCVoiceCall Insert Delete Resource event 10:14:57.851 10.151.3.14 local0.notice [S=1375] [SID=58c3aa:28:12] ( lgr_flow)( 1033) (#128) SBCCallPlacementFeature changing states from: Releasing to: EndState 10:14:57.852 10.151.3.14 local0.notice [S=1376] [SID=58c3aa:28:12] ( lgr_gwapp_ha)( 1034) (#128)SBCCallPlacementFeature Insert Delete Resource event 10:14:57.854 10.151.3.14 local1.info [S=13] |CALL_END |SBC |12598589641120100423@10.151.3.14 |58c3aa:28:12 |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:04:23.498 UTC Fri Jan 01 2010 | |00:04:29.871 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 10:14:57.858 10.151.3.14 local0.notice [S=1377] [SID=58c3aa:28:12] ( media_service)( 1035) (#4) MediaResourceAdapter Deallocated. 10:14:57.858 10.151.3.14 local0.notice [S=1378] [SID=58c3aa:28:12] ( lgr_flow)( 1036) (#14)RTPStreamResource::DeAllocateResource, CID -100 Detached. 10:14:57.858 10.151.3.14 local0.notice [S=1379] [SID=58c3aa:28:12] ( lgr_flow)( 1037) (#4)ChannelResource::DeAllocateMediaIpPorts RealmIndex(0) port(6070) IPV4IF(0) Deallocated. 10:14:57.858 10.151.3.14 local0.notice [S=1380] [SID=58c3aa:28:12] ( media_service)( 1038) (#4) ChannelResource Deallocated. 10:14:57.858 10.151.3.14 local0.notice [S=1381] [SID=58c3aa:28:12] ( media_service)( 1039) (#14) RTPStreamResource Deallocated. 10:14:57.859 10.151.3.14 local0.notice [S=1382] [SID=58c3aa:28:12] ( lgr_gwapp_ha)( 1040) (#20)SBCVoiceCall Insert Delete Resource event 10:14:57.860 10.151.3.14 local0.notice [S=1383] [SID=58c3aa:28:12] ( lgr_stk_ses)( 1041) SIPSBCCallLeg(#4054) Deallocated 10:14:57.860 10.151.3.14 local0.notice [S=1384] [SID=58c3aa:28:12] ( sip_stack)( 1042) Setting ApplicationCall of AcSIPCall(#4660) to NULL 10:14:57.861 10.151.3.14 local0.notice [S=1385] [SID=58c3aa:28:12] ( sip_stack)( 1043) SDPBodyMedia::Delete - id = (#448) 10:14:57.862 10.151.3.14 local0.notice [S=1386] [SID=58c3aa:28:12] ( sip_stack)( 1044) Resource BufferSDPBody deleted - (#169) 10:14:57.865 10.151.3.14 local0.notice [S=1387] [SID=58c3aa:28:12] ( lgr_flow)( 1045) (#140) MediaMetaDataList Deallocated. 10:14:57.866 10.151.3.14 local0.notice [S=1388] [SID=58c3aa:28:12] ( lgr_stk_ses)( 1046) DirectMediaDB::RemoveData Removed DirectMediaData from Database IP:10.151.3.14 Port:6070 SDP:(#173) 10:14:57.867 10.151.3.14 local0.notice [S=1389] [SID=58c3aa:28:12] ( sip_stack)( 1047) SDPBodyMedia::Delete - id = (#456) 10:14:57.868 10.151.3.14 local0.notice [S=1390] [SID=58c3aa:28:12] ( sip_stack)( 1048) Resource BufferSDPBody deleted - (#173) 10:14:57.869 10.151.3.14 local0.notice [S=1391] [SID=58c3aa:28:12] ( lgr_stk_ses)( 1049) SIPBaseStackSession(#4054)::ProcessQueuedEvents: StackSession is released. Stopping iteration. 10:14:57.870 10.151.3.14 local0.notice [S=1392] [SID=58c3aa:28:12] ( media_service)( 1050) (#2037) CONTROLLER Deallocated. 10:14:57.871 10.151.3.14 local0.notice [S=1393] [SID=58c3aa:28:12] ( lgr_sbc)( 1051) 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 10:14:57.871 10.151.3.14 local0.notice [S=1394] [SID=58c3aa:28:12] ( media_service)( 1052) ServicesMngr: Deallocate SBC leg. current active: 2 and max is: 10 10:14:57.872 10.151.3.14 local0.notice [S=1395] [SID=58c3aa:28:12] ( lgr_call)( 1053) (#23) CALL Deallocated. 10:14:57.878 10.151.3.14 local0.notice [S=1396] [SID=58c3aa:28:12] ( lgr_flow)( 1054) (#143) MediaMetaDataList Deallocated. 10:14:57.878 10.151.3.14 local0.notice [S=1397] [SID=58c3aa:28:12] ( sip_stack)( 1055) Resource SIPMessage deleted - (#84) 10:14:57.879 10.151.3.14 local0.notice [S=1398] [SID=58c3aa:28:12] ( sip_stack)( 1056) SDPBody::Delete - id = (#50) 10:14:57.881 10.151.3.14 local0.notice [S=1399] [SID=58c3aa:28:12] ( sip_stack)( 1057) SDPBodyMedia::Delete - id = (#457) 10:14:57.881 10.151.3.14 local0.notice [S=1400] [SID=58c3aa:28:12] ( sip_stack)( 1058) Resource SIPMessage deleted - (#90) 10:14:57.881 10.151.3.14 local0.notice [S=1401] [SID=58c3aa:28:12] ( lgr_flow)( 1059) (#141) MediaMetaDataList Deallocated. 10:14:57.883 10.151.3.14 local0.notice [S=1402] [SID=58c3aa:28:12] ( lgr_sbc)( 1060) (#118) SBCRoutesIterator Deallocated. 10:14:57.883 10.151.3.14 local0.notice [S=1403] [SID=58c3aa:28:12] ( lgr_sbc)( 1061) (#128) FEATURE Deallocated. 10:14:57.886 10.151.3.14 local0.notice [S=1404] [SID=58c3aa:28:12] ( lgr_sbc)( 1062) 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 10:14:57.886 10.151.3.14 local0.notice [S=1405] [SID=58c3aa:28:12] ( media_service)( 1063) ServicesMngr: Deallocate SBC leg. current active: 1 and max is: 10 10:14:57.886 10.151.3.14 local0.notice [S=1406] [SID=58c3aa:28:12] ( lgr_call)( 1064) (#20) CALL Deallocated. 10:14:57.886 10.151.3.14 local0.notice [S=1407] [SID=58c3aa:28:12] ( sip_stack)( 1065) ---- Incoming SIP Message from 10.151.3.13:5060 to SIPInterface #0 (SBC) UDP TO(#0) ---- 10:14:57.887 10.151.3.14 local0.notice [S=1408] [SID=58c3aa:28:12] ACK sip:1000@10.151.3.14 SIP/2.0 Via: SIP/2.0/UDP 10.151.3.13:5060;branch=z9hG4bK-524287-1---ebb395234ac7652e;rport Max-Forwards: 70 To: ;tag=1c686575526 From: "05333096548";tag=ce219c37 Call-ID: 88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q CSeq: 1 ACK Content-Length: 0 10:14:57.889 10.151.3.14 local0.notice [S=1409] [SID=58c3aa:28:12] ( sip_stack)( 1066) New SIPMessage created - (#75) 10:14:57.890 10.151.3.14 local0.notice [S=1410] [SID=58c3aa:28:12] ( sip_stack)( 1067) UdpRtxMngr::Remove 487 Response 1 INVITE 10:14:57.892 10.151.3.14 local0.notice [S=1411] [SID=58c3aa:28:12] ( lgr_flow)( 1068) | |(SIPTU(#4663)ACK State:Disconnected(88254MmYyNmQwOTk3Y2QyNzNjYzllOTQ1YWEzNDIzODllM2Q) 10:14:57.893 10.151.3.14 local0.notice [S=1412] [SID=58c3aa:28:12] ( sip_stack)( 1069) Resource SIPMessage deleted - (#75)