We are having problems routing calls out from out phones.
I'll try it brief, but it looks like the branch is being truncated, which is resulting in a 2 second delay on voice traffic being passed. For exapmple, we dial out and get ringback both ends. Answer at the remote end and it takes 2 seconds before any audio passes.
The route is Aastra 9133i Phone > TeleVantage 7.5 Server > AudioCodes Mediant 2000 > ISDN PRI
It is going through a firewall, but NAT is off and it is ANY ANY rules (according to our network team).
However if I do a call direct from my phone to the A/C, there is no delay.
When we did this, we noticed the branch is NOT being truncated.
We have a feeling this is the cause, but we have no idea what the cause, therefore not sure where to resolve.
A syslog from thr Audiocodes is Posted. Soory if a bit to long.
Most people spend their time on the "urgent" rather than on the "important."
I'll try it brief, but it looks like the branch is being truncated, which is resulting in a 2 second delay on voice traffic being passed. For exapmple, we dial out and get ringback both ends. Answer at the remote end and it takes 2 seconds before any audio passes.
The route is Aastra 9133i Phone > TeleVantage 7.5 Server > AudioCodes Mediant 2000 > ISDN PRI
It is going through a firewall, but NAT is off and it is ANY ANY rules (according to our network team).
However if I do a call direct from my phone to the A/C, there is no delay.
When we did this, we noticed the branch is NOT being truncated.
We have a feeling this is the cause, but we have no idea what the cause, therefore not sure where to resolve.
A syslog from thr Audiocodes is Posted. Soory if a bit to long.
Code:
13:36:28.855 : 10.15.70.11 : NOTICE : ( lgr_flow)(8313 ) ---- Incoming SIP Message from 10.32.3.171:5060 ---- [Time: 12:36:27]
13:36:28.865 : 10.15.70.11 : NOTICE : INVITE sip:079XXXXXXXX@10.15.70.11;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.32.3.171;rport;branch=z9hG4bK+e609209198c0c70479ddb58c1b1cb39c+10.32.3.171+1 Allow-Events: message-summary Allow-Events: presence Allow-Events: refer Max-Forwards: 70 Call-ID: B0819C1D@10.32.3.171 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e00001+b440bc4a To: <sip:079XXXXXXXX@10.15.70.11;user=phone> CSeq: 855103514 INVITE Expires: 180 Supported: replaces Supported: 100rel Contact: <sip:10.32.3.171> Remote-Party-ID: "Stu Reeves" <sip:7204101@10.32.3.171;user=phone>;party=calling;privacy=off;npi=1;ton=1 Content-Type: application/sdp Content-Length: 199 User-Agent: TeleVantage-UA/7.50.4961 v=0 o=TeleVantage 24 0 IN IP4 10.32.3.171 s=phone-call c=IN IP4 10.32.3.171 t=0 0 m=audio 6006 RTP/AVP 18 101 a=rtpmap:18 g729/8000/1 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000/1 [Time: 12:36:27]
13:36:28.895 : 10.15.70.11 : NOTICE : ( sip_stack)(8315 ) AcSIPParser: Problem in SIP Message Headers [Time: 12:36:27]
13:36:28.905 : 10.15.70.11 : WARNING : ( sip_stack)(8316 ) ?? [WARNING] AcSIPParser: Warning. Branch is too long. Truncated to 49 chars [Time: 12:36:27]
13:36:28.915 : 10.15.70.11 : WARNING : ( sip_stack)(8317 ) ?? [WARNING] Message type: INVITE [Time: 12:36:27]
13:36:28.925 : 10.15.70.11 : WARNING : ( sip_stack)(8318 ) ?? [WARNING] Source header: [Time: 12:36:27]
13:36:28.925 : 10.15.70.11 : WARNING : ( sip_stack)(8319 ) ?? [WARNING] Line: 2. Column: 43 [Time: 12:36:27]
13:36:28.935 : 10.15.70.11 : NOTICE : ( sip_stack)(8320 ) AcSIPParser: Unrecognized Header was detected at line: 3 [Time: 12:36:27]
13:36:28.945 : 10.15.70.11 : NOTICE : ( sip_stack)(8321 ) AcSIPParser: Unrecognized Header was detected at line: 4 [Time: 12:36:27]
13:36:28.955 : 10.15.70.11 : NOTICE : ( sip_stack)(8322 ) AcSIPParser: Unrecognized Header was detected at line: 5 [Time: 12:36:27]
13:36:28.975 : 10.15.70.11 : NOTICE : ( lgr_flow)(8323 ) | | new GetNewSIPCall created - #136 [Time: 12:36:27]
13:36:28.975 : 10.15.70.11 : NOTICE : ( sip_stack)(8324 ) new AcSIPCallAPI created - #99 [Time: 12:36:27]
13:36:28.986 : 10.15.70.11 : NOTICE : ( lgr_stk_mngr)(8325 ) Resource StackSession <#99> Allocated [Time: 12:36:27]
13:36:28.996 : 10.15.70.11 : NOTICE : ( lgr_flow)(8326 ) | |(SIPTU#136)INVITE State:Idle() [Time: 12:36:27]
13:36:28.996 : 10.15.70.11 : NOTICE : ( sip_stack)(8327 ) SIPCall(#136) changes state from Idle to Invited [Time: 12:36:27]
13:36:29.006 : 10.15.70.11 : NOTICE : ( lgr_flow)(8328 ) | | | #99:SIP_SETUP_EV(B0819C1D@10.32.3.171) [Time: 12:36:27]
13:36:29.016 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8329 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 [Time: 12:36:27]
13:36:29.026 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8330 ) <SESSION #99> SendToCall - event: NEW_CALL_EV m_Call = 35533568 [Time: 12:36:27]
13:36:29.026 : 10.15.70.11 : NOTICE : ( lgr_flow)(8331 ) | | #99:NEW_CALL_EV:(B0819C1D@10.32.3.171) [Time: 12:36:27]
13:36:29.036 : 10.15.70.11 : NOTICE : ( lgr_flow)(8332 ) | | #99:Call changing states from:IdleState to:NewCallState_IP2Tel [Time: 12:36:27]
13:36:29.046 : 10.15.70.11 : NOTICE : ( lgr_flow)(8333 ) ServicesMngr::GetEndPoint PhoneNum = 079XXXXXXXX [Time: 12:36:27]
13:36:29.056 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8334 ) GetTrunkGroupId- TrunkGroup:2 found DstNum:079XXXXXXXX DstPfx:* SrcNum:7204101 SrcPfx:* SrcIp:a2003ab SrcIpPfx:* [Time: 12:36:27]
13:36:29.056 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8335 ) Current trunks status: 1110 [Time: 12:36:27]
13:36:29.066 : 10.15.70.11 : NOTICE : ( lgr_profiling)(8336 ) CIpProfile::GetProfileIdFromIpToTelTable DNIS:079XXXXXXXX,ANI:7204101,DestAddress:10.32.3.171 :Profile Found <2> [Time: 12:36:27]
13:36:29.066 : 10.15.70.11 : NOTICE : ( lgr_profiling)(8337 ) <Call 99> Profiled<Tel=2,Ip=2>: TelCoderGrId=2 IpCoderGrId=2 JBMinDel=70 JBOptF=10 EEarlyM=1 FaxTM=2 IPDS=46 IsFaxU=2 PI2IP=-1 SigIPDF=40 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 RTPRD=0 SCE=0 VxxTT=2 DTMFVol=20 ECE=1 ECurDis=0 EDigDel=0 ERevP=0 FHPer=400 InG=37 MWIA=0 MWID=0 VVol=37 [Time: 12:36:27]
13:36:29.076 : 10.15.70.11 : NOTICE : ( lgr_flow)(8338 ) | #62:NEW_CALL_EV : (B0819C1D@10.32.3.171) [Time: 12:36:27]
13:36:29.076 : 10.15.70.11 : NOTICE : ( sip_stack)(8339 ) SIPSDPSession#99 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED [Time: 12:36:27]
13:36:29.086 : 10.15.70.11 : NOTICE : ( lgr_flow)(8340 ) | |(SIPTU#136)TRYING_REQ State:Invited(B0819C1D@10.32.3.171) [Time: 12:36:27]
13:36:29.086 : 10.15.70.11 : NOTICE : ( lgr_flow)(8341 ) ---- Outgoing SIP Message to 10.32.3.171:5060 ---- [Time: 12:36:27]
13:36:29.086 : 10.15.70.11 : NOTICE : SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.32.3.171;rport;branch=z9hG4bK+e609209198c0c70479ddb58c1b1cb39c+10.32.3.171+1 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e00001+b440bc4a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c196474796 Call-ID: B0819C1D@10.32.3.171 CSeq: 855103514 INVITE Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-Mediant 2000/v.5.00A.044.003 Content-Length: 0 [Time: 12:36:27]
13:36:29.126 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8343 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 [Time: 12:36:27]
13:36:29.126 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8344 ) <SESSION #99> SendToCall - event: SETUP m_Call = 35533568 [Time: 12:36:27]
13:36:29.126 : 10.15.70.11 : NOTICE : ( lgr_flow)(8345 ) | | #99:SETUP (TO:079XXXXXXXX, FROM:7204101):(B0819C1D@10.32.3.171) [Time: 12:36:27]
13:36:29.136 : 10.15.70.11 : NOTICE : ( lgr_flow)(8346 ) | | #99:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel [Time: 12:36:27]
13:36:29.136 : 10.15.70.11 : NOTICE : ( lgr_flow)(8347 ) | #62:SETUP : (B0819C1D@10.32.3.171) [Time: 12:36:27]
13:36:29.146 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8348 ) pstn send --> PlaceCall: Trunk:2 BChannel:1 ConnID:0 SrcPN=7204101 SrcSN= DstPN=079XXXXXXXX DstSN= SrcNT=1 SrcNP=1 SrcPres=0 SrcScrn=0 DstNT=0 DstNP=0 ServiceCap=M RdrctNum= RdNT=0 RdNP=0 RdPres=0 RdScrn=0 RdRsn=-1 Excl=1 Display=Stu Reeves IE= UUIE=0,, CLIRReason:-1 [Time: 12:36:27]
13:36:29.146 : 10.15.70.11 : NOTICE : ( lgr_psbrdex)(8349 ) pstn recv <-- CALL_PROCEEDING Trunk:2 Conn:0 BChannel:1 callhndl:0 Loc:-1 Des:-1 [Time: 12:36:27]
13:36:29.156 : 10.15.70.11 : NOTICE : ( lgr_flow)(8350 ) #62:LOCAL_CALL_PROCEEDING_EV(Trunk:2 Conn:0 Bchannel:1 TpEv=73) [Time: 12:36:27]
13:36:29.156 : 10.15.70.11 : NOTICE : ( lgr_flow)(8351 ) | #62:LOCAL_CALL_PROCEEDING_EV [Time: 12:36:27]
13:36:29.156 : 10.15.70.11 : NOTICE : ( lgr_flow)(8352 ) | #62:PROCEEDING (send) : (B0819C1D@10.32.3.171) [Time: 12:36:27]
13:36:29.166 : 10.15.70.11 : NOTICE : ( lgr_flow)(8353 ) | | #99:PROCEEDING:(B0819C1D@10.32.3.171) [Time: 12:36:27]
13:36:29.166 : 10.15.70.11 : NOTICE : ( lgr_flow)(8354 ) | | | #99:PROCEEDING(B0819C1D@10.32.3.171) [Time: 12:36:27]
13:36:35.519 : 10.15.70.11 : NOTICE : ( lgr_psbrdex)(8355 ) pstn recv <-- CALL_ALERTING Trunk:2 Conn:0 BChannel:1 [Time: 12:36:34]
13:36:35.539 : 10.15.70.11 : NOTICE : ( lgr_flow)(8356 ) #62:LOCAL_ALERTING_EV(Trunk:2 Conn:0 Bchannel:1 TpEv=69) [Time: 12:36:34]
13:36:35.539 : 10.15.70.11 : NOTICE : ( lgr_flow)(8357 ) | #62:LOCAL_ALERTING_EV [Time: 12:36:34]
13:36:35.549 : 10.15.70.11 : NOTICE : ( lgr_flow)(8358 ) | #62:ALERT (send) : (B0819C1D@10.32.3.171) [Time: 12:36:34]
13:36:35.559 : 10.15.70.11 : NOTICE : ( lgr_flow)(8359 ) | | #99:ALERT:(B0819C1D@10.32.3.171) [Time: 12:36:34]
13:36:35.559 : 10.15.70.11 : NOTICE : ( lgr_flow)(8360 ) | | #99:Call changing states from:InitiatedState_IP2Tel to:AlertingState_IP2Tel [Time: 12:36:34]
13:36:35.569 : 10.15.70.11 : NOTICE : ( lgr_flow)(8361 ) | | | #99:ALERT(B0819C1D@10.32.3.171) [Time: 12:36:34]
13:36:35.569 : 10.15.70.11 : NOTICE : ( lgr_flow)(8362 ) | |(SIPTU#136)ALERT_REQ State:Invited(B0819C1D@10.32.3.171) [Time: 12:36:34]
13:36:35.579 : 10.15.70.11 : NOTICE : ( lgr_flow)(8363 ) ---- Outgoing SIP Message to 10.32.3.171:5060 ---- [Time: 12:36:34]
13:36:35.589 : 10.15.70.11 : NOTICE : SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.32.3.171;rport;branch=z9hG4bK+e609209198c0c70479ddb58c1b1cb39c+10.32.3.171+1 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e00001+b440bc4a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c196474796 Call-ID: B0819C1D@10.32.3.171 CSeq: 855103514 INVITE Contact: <sip:61@10.15.70.11> Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Require: 100rel RSeq: 1 Server: Audiocodes-Sip-Gateway-Mediant 2000/v.5.00A.044.003 Content-Length: 0 [Time: 12:36:34]
13:36:35.649 : 10.15.70.11 : NOTICE : ( sip_stack)(8365 ) UdpRtxMngr::Transmit 180 Response 855103514 INVITE Rtx Left: 6 Dest: a2003ab:5060 [Time: 12:36:34]
13:36:35.659 : 10.15.70.11 : NOTICE : ( sip_stack)(8366 ) SIPTransaction::ResendLastMessage - Resending last message [Time: 12:36:34]
13:36:35.669 : 10.15.70.11 : NOTICE : ( lgr_flow)(8367 ) ---- Incoming SIP Message from 10.32.3.171:5060 ---- [Time: 12:36:34]
13:36:35.669 : 10.15.70.11 : NOTICE : PRACK sip:079XXXXXXXX@10.15.70.11;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+33f2ed41149635fe79f26018f27e27e7+10.32.3.171+1 Allow-Events: message-summary Allow-Events: presence Allow-Events: refer Max-Forwards: 70 Call-ID: B0819C1D@10.32.3.171 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e00001+b440bc4a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c196474796 CSeq: 855103515 PRACK RAck: 1 855103514 INVITE Supported: replaces Supported: 100rel Content-Length: 0 User-Agent: TeleVantage-UA/7.50.4961 [Time: 12:36:34]
13:36:35.749 : 10.15.70.11 : NOTICE : ( sip_stack)(8369 ) AcSIPParser: Problem in SIP Message Headers [Time: 12:36:34]
13:36:35.759 : 10.15.70.11 : WARNING : ( sip_stack)(8370 ) ?? [WARNING] AcSIPParser: Warning. Branch is too long. Truncated to 49 chars [Time: 12:36:34]
13:36:35.759 : 10.15.70.11 : WARNING : ( sip_stack)(8371 ) ?? [WARNING] Message type: PRACK [Time: 12:36:34]
13:36:35.769 : 10.15.70.11 : WARNING : ( sip_stack)(8372 ) ?? [WARNING] Source header: [Time: 12:36:34]
13:36:35.769 : 10.15.70.11 : WARNING : ( sip_stack)(8373 ) ?? [WARNING] Line: 2. Column: 37 [Time: 12:36:34]
13:36:35.779 : 10.15.70.11 : NOTICE : ( sip_stack)(8374 ) AcSIPParser: Unrecognized Header was detected at line: 3 [Time: 12:36:34]
13:36:35.789 : 10.15.70.11 : NOTICE : ( sip_stack)(8375 ) AcSIPParser: Unrecognized Header was detected at line: 4 [Time: 12:36:34]
13:36:35.799 : 10.15.70.11 : NOTICE : ( sip_stack)(8376 ) AcSIPParser: Unrecognized Header was detected at line: 5 [Time: 12:36:34]
13:36:35.809 : 10.15.70.11 : NOTICE : ( lgr_flow)(8377 ) | |(SIPTU#136)PRACK State:Invited(B0819C1D@10.32.3.171) [Time: 12:36:34]
13:36:35.819 : 10.15.70.11 : NOTICE : ( sip_stack)(8378 ) UdpRtxMngr::Remove 180 Response 855103514 INVITE [Time: 12:36:34]
13:36:35.819 : 10.15.70.11 : NOTICE : ( lgr_flow)(8379 ) ---- Outgoing SIP Message to 10.32.3.171:5060 ---- [Time: 12:36:34]
13:36:35.829 : 10.15.70.11 : NOTICE : SIP/2.0 200 OK Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+33f2ed41149635fe79f26018f27e27e7+10.32.3.171+1 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e00001+b440bc4a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c196474796 Call-ID: B0819C1D@10.32.3.171 CSeq: 855103515 PRACK Contact: <sip:61@10.15.70.11> Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-Mediant 2000/v.5.00A.044.003 Content-Length: 0 [Time: 12:36:34]
13:36:35.869 : 10.15.70.11 : NOTICE : ( lgr_flow)(8381 ) | | | #99:SIP_PRACK_RESPONDED_EV(B0819C1D@10.32.3.171) [Time: 12:36:34]
13:36:41.470 : 10.15.70.11 : NOTICE : ( lgr_psbrdex)(8382 ) pstn recv <-- CALL_CONNECTED Trunk:2 Conn:0 BChannel:1 Number: [Time: 12:36:40]
13:36:41.490 : 10.15.70.11 : NOTICE : ( lgr_flow)(8383 ) #62:LOCAL_CALL_CONNECTED_EV(Trunk:2 Conn:0 Bchannel:1 TpEv=65) [Time: 12:36:40]
13:36:41.490 : 10.15.70.11 : NOTICE : ( lgr_flow)(8384 ) | #62:LOCAL_CALL_CONNECTED_EV [Time: 12:36:40]
13:36:41.500 : 10.15.70.11 : NOTICE : ( lgr_flow)(8385 ) | #62:OPEN_VOICE-NOT_READY (MS:0, HS:0, Ready:1) [Time: 12:36:40]
13:36:41.511 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8386 ) #62:PSOSBoardInterface::StopPlayTone- Called [Time: 12:36:40]
13:36:41.521 : 10.15.70.11 : NOTICE : ( lgr_flow)(8387 ) | #62:CONNECT (send) : (B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:41.521 : 10.15.70.11 : NOTICE : ( lgr_flow)(8388 ) | | #99:CONNECT:(B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:41.531 : 10.15.70.11 : NOTICE : ( lgr_flow)(8389 ) | | #99:Call changing states from:AlertingState_IP2Tel to:ConnectedState [Time: 12:36:40]
13:36:41.541 : 10.15.70.11 : NOTICE : ( lgr_flow)(8390 ) | | | #99:CONNECT(B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:41.541 : 10.15.70.11 : NOTICE : ( sip_stack)(8391 ) SIPSDPSession#99 - Changing state from SIP_MEDIA_OFFERED to SIP_MEDIA_COMPLETED [Time: 12:36:40]
13:36:41.551 : 10.15.70.11 : NOTICE : ( lgr_flow)(8392 ) | |(SIPTU#136)CONNECT_REQ State:Invited(B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:41.561 : 10.15.70.11 : NOTICE : ( lgr_flow)(8393 ) ---- Outgoing SIP Message to 10.32.3.171:5060 ---- [Time: 12:36:40]
13:36:41.561 : 10.15.70.11 : NOTICE : SIP/2.0 200 OK Via: SIP/2.0/UDP 10.32.3.171;rport;branch=z9hG4bK+e609209198c0c70479ddb58c1b1cb39c+10.32.3.171+1 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e00001+b440bc4a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c196474796 Call-ID: B0819C1D@10.32.3.171 CSeq: 855103514 INVITE Contact: <sip:61@10.15.70.11> Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-Mediant 2000/v.5.00A.044.003 Content-Type: application/sdp Content-Length: 252 v=0 o=AudiocodesGW 196554134 196553828 IN IP4 10.15.70.11 s=Phone-Call c=IN IP4 10.15.70.11 t=0 0 m=audio 6620 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv [Time: 12:36:40]
13:36:41.681 : 10.15.70.11 : NOTICE : ( sip_stack)(8395 ) UdpRtxMngr::Transmit 200 Response 855103514 INVITE Rtx Left: 6 Dest: a2003ab:5060 [Time: 12:36:40]
13:36:41.691 : 10.15.70.11 : NOTICE : ( sip_stack)(8396 ) SIPTransaction::ResendLastMessage - Resending last message [Time: 12:36:40]
13:36:41.691 : 10.15.70.11 : NOTICE : ( sip_stack)(8397 ) SIPCall(#136) changes state from Invited to LocalAccepted [Time: 12:36:40]
13:36:41.701 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8398 ) DtmfCapNegotiationAlgorithm :: TxDtmfMethod = DTMF_RFC2833_SUPPORTED [Time: 12:36:40]
13:36:41.701 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8399 ) DtmfCapNegotiationAlgorithm :: TxRtpRfc2833Payload = 101 [Time: 12:36:40]
13:36:41.701 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8400 ) <SESSION #99> SendToCall - event: DTMF_CONTROL_EV m_Call = 35533568 [Time: 12:36:40]
13:36:41.711 : 10.15.70.11 : NOTICE : ( lgr_flow)(8401 ) | | #99:DTMF_CONTROL_EV:(B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:41.711 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8402 ) <SESSION #99> SendToCall - event: OPEN_LOGICAL_CHANNEL_ACK m_Call = 35533568 [Time: 12:36:40]
13:36:41.721 : 10.15.70.11 : NOTICE : ( lgr_flow)(8403 ) | | #99:OPEN_LOGICAL_CHANNEL_ACK:(B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:41.721 : 10.15.70.11 : NOTICE : ( lgr_flow)(8404 ) | #62:OPEN_LOGICAL_CHANNEL_ACK : (B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:41.731 : 10.15.70.11 : NOTICE : ( lgr_flow)(8405 ) | #62:OPEN_VOICE (IP:a2003ab, RTP:6006, RTCP:6007, VoiceCoder:g72920, VbdCoder: InvalidCoder255, Dtmf:gwRFC2833RalayDTMF,Rx payload:101,Tx payload:101 ,RTPmode:1, FaxTransportType: 3) [Time: 12:36:40]
13:36:41.731 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8406 ) #62:ActivateChannel: Socks=15 CID=62 Trunk:2 BChannel:1 RemoteIP=a2003ab RemotePort=6006 RemoteRTCPIP=a2003ab RemoteRTCPPort=6007 FaxModemDet=NO_FAX_MODEM_DETECTED [Time: 12:36:40]
13:36:41.731 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8407 ) #62:OpenChannel:on Trunk 2 BChannel:1 CID=62 with VoiceCoder: g72920 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 12:36:40]
13:36:41.741 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8408 ) #62:OpenChannel VoiceVolume= 5, DTMFVolume = -11, InputGain = 5, RTPRedundancyDepth = 0 FlashHookPeriod = 400 [Time: 12:36:40]
13:36:41.741 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8409 ) Detectors: Amd:0, Ans:0 En:0 IBScmd:0xa1 [Time: 12:36:40]
13:36:41.751 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8410 ) OpenChannel, CoderType = 17, Interval = 3, M = 1 [Time: 12:36:40]
13:36:41.751 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8411 ) #62:FAXTransportType = 3 [Time: 12:36:40]
13:36:41.761 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8412 ) #62:PSOSBoardInterface::ConfigureFaxModemChannelParams FAXTransportType=3 Modem configuration VxxTransportType=2 not allowed, forced to 3 [Time: 12:36:40]
13:36:41.761 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8413 ) #62:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=3, VxxTranType=3, VoiceVol= 5, DTMFVol=-11, InGain=5, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=70, DJBufOptFac=10) [Time: 12:36:40]
13:36:41.771 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8414 ) Setting ActivateRTP_RTCPCmd.Cmd.IpTosFieldInUdpPacket to 184 [Time: 12:36:40]
13:36:41.771 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8415 ) #62:ActivateChannel:RtpPayload: 18 [Time: 12:36:40]
13:36:41.781 : 10.15.70.11 : NOTICE : ( lgr_flow)(8416 ) ---- Incoming SIP Message from 10.32.3.171:5060 ---- [Time: 12:36:40]
13:36:41.781 : 10.15.70.11 : NOTICE : ACK sip:61@10.15.70.11 SIP/2.0 Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+4f96a2797a2f3eadf660ed80dbdcec07+10.32.3.171+1 Allow-Events: message-summary Allow-Events: presence Allow-Events: refer Max-Forwards: 70 Call-ID: B0819C1D@10.32.3.171 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e00001+b440bc4a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c196474796 CSeq: 855103514 ACK Contact: <sip:10.32.3.171> Content-Length: 0 User-Agent: TeleVantage-UA/7.50.4961 [Time: 12:36:40]
13:36:41.821 : 10.15.70.11 : NOTICE : ( sip_stack)(8418 ) AcSIPParser: Problem in SIP Message Headers [Time: 12:36:40]
13:36:41.821 : 10.15.70.11 : WARNING : ( sip_stack)(8419 ) ?? [WARNING] AcSIPParser: Warning. Branch is too long. Truncated to 49 chars [Time: 12:36:40]
13:36:41.831 : 10.15.70.11 : WARNING : ( sip_stack)(8420 ) ?? [WARNING] Message type: ACK [Time: 12:36:40]
13:36:41.831 : 10.15.70.11 : WARNING : ( sip_stack)(8421 ) ?? [WARNING] Source header: [Time: 12:36:40]
13:36:41.831 : 10.15.70.11 : WARNING : ( sip_stack)(8422 ) ?? [WARNING] Line: 2. Column: 37 [Time: 12:36:40]
13:36:41.841 : 10.15.70.11 : NOTICE : ( sip_stack)(8423 ) AcSIPParser: Unrecognized Header was detected at line: 3 [Time: 12:36:40]
13:36:41.841 : 10.15.70.11 : NOTICE : ( sip_stack)(8424 ) AcSIPParser: Unrecognized Header was detected at line: 4 [Time: 12:36:40]
13:36:41.851 : 10.15.70.11 : NOTICE : ( sip_stack)(8425 ) AcSIPParser: Unrecognized Header was detected at line: 5 [Time: 12:36:40]
13:36:41.851 : 10.15.70.11 : NOTICE : ( sip_stack)(8426 ) UdpRtxMngr::Remove 200 Response 855103514 INVITE [Time: 12:36:40]
13:36:41.861 : 10.15.70.11 : NOTICE : ( lgr_flow)(8427 ) | |(SIPTU#136)ACK State:LocalAccepted(B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:41.861 : 10.15.70.11 : NOTICE : ( sip_stack)(8428 ) SIPCall(#136) changes state from LocalAccepted to Connected [Time: 12:36:40]
13:36:41.871 : 10.15.70.11 : NOTICE : ( lgr_flow)(8429 ) | | | #99:SIP_ACK_EV(B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:41.871 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8430 ) <SESSION #99> SendToCall - event: CONNECT_ACK_EV m_Call = 35533568 [Time: 12:36:40]
13:36:41.871 : 10.15.70.11 : NOTICE : ( lgr_flow)(8431 ) | | #99:CONNECT_ACK_EV:(B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:41.881 : 10.15.70.11 : NOTICE : ( lgr_flow)(8432 ) | #62:CONNECT_ACK_EV : (B0819C1D@10.32.3.171) [Time: 12:36:40]
13:36:45.038 : 10.15.70.11 : NOTICE : ( lgr_flow)(8433 ) ---- Incoming SIP Message from 10.32.3.171:5060 ---- [Time: 12:36:43]
13:36:45.048 : 10.15.70.11 : NOTICE : BYE sip:61@10.15.70.11 SIP/2.0 Via: SIP/2.0/UDP 10.32.3.171;rport;branch=z9hG4bK+38970d0e5741df185fdcf00830022fdd+10.32.3.171+1 Allow-Events: message-summary Allow-Events: presence Allow-Events: refer Max-Forwards: 70 Call-ID: B0819C1D@10.32.3.171 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e00001+b440bc4a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c196474796 CSeq: 855103516 BYE Supported: replaces Supported: 100rel Content-Length: 0 User-Agent: TeleVantage-UA/7.50.4961 [Time: 12:36:43]
13:36:45.108 : 10.15.70.11 : NOTICE : ( sip_stack)(8435 ) AcSIPParser: Problem in SIP Message Headers [Time: 12:36:43]
13:36:45.118 : 10.15.70.11 : WARNING : ( sip_stack)(8436 ) ?? [WARNING] AcSIPParser: Warning. Branch is too long. Truncated to 49 chars [Time: 12:36:43]
13:36:45.128 : 10.15.70.11 : WARNING : ( sip_stack)(8437 ) ?? [WARNING] Message type: BYE [Time: 12:36:43]
13:36:45.128 : 10.15.70.11 : WARNING : ( sip_stack)(8438 ) ?? [WARNING] Source header: [Time: 12:36:43]
13:36:45.138 : 10.15.70.11 : WARNING : ( sip_stack)(8439 ) ?? [WARNING] Line: 2. Column: 43 [Time: 12:36:43]
13:36:45.138 : 10.15.70.11 : NOTICE : ( sip_stack)(8440 ) AcSIPParser: Unrecognized Header was detected at line: 3 [Time: 12:36:43]
13:36:45.148 : 10.15.70.11 : NOTICE : ( sip_stack)(8441 ) AcSIPParser: Unrecognized Header was detected at line: 4 [Time: 12:36:43]
13:36:45.158 : 10.15.70.11 : NOTICE : ( sip_stack)(8442 ) AcSIPParser: Unrecognized Header was detected at line: 5 [Time: 12:36:43]
13:36:45.168 : 10.15.70.11 : NOTICE : ( lgr_flow)(8443 ) | |(SIPTU#136)BYE State:Connected(B0819C1D@10.32.3.171) [Time: 12:36:43]
13:36:45.178 : 10.15.70.11 : NOTICE : ( sip_stack)(8444 ) SIPCall(#136) changes state from Connected to Disconnected [Time: 12:36:43]
13:36:45.188 : 10.15.70.11 : NOTICE : ( lgr_flow)(8445 ) | | | #99:SIP_DISCONNECT_EV(B0819C1D@10.32.3.171) [Time: 12:36:43]
13:36:45.188 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8446 ) <SESSION #99> SendToCall - event: RELEASE m_Call = 35533568 [Time: 12:36:43]
13:36:45.198 : 10.15.70.11 : NOTICE : ( lgr_flow)(8447 ) | | #99:RELEASE:(B0819C1D@10.32.3.171) [Time: 12:36:43]
13:36:45.198 : 10.15.70.11 : NOTICE : ( lgr_flow)(8448 ) | | #99:Call changing states from:ConnectedState to:DisconnectingState [Time: 12:36:43]
13:36:45.208 : 10.15.70.11 : NOTICE : ( lgr_flow)(8449 ) | | #99:RELEASE_ACK:(B0819C1D@10.32.3.171) [Time: 12:36:43]
13:36:45.208 : 10.15.70.11 : NOTICE : ( lgr_flow)(8450 ) | #62:RELEASE GWAPP_NORMAL_CALL_CLEAR : (B0819C1D@10.32.3.171) [Time: 12:36:43]
13:36:45.218 : 10.15.70.11 : NOTICE : ( lgr_flow)(8451 ) | #62:Close voice Channel [Time: 12:36:43]
13:36:45.228 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8452 ) #62:StopRTP_RTCP on channel 62 [Time: 12:36:43]
13:36:45.228 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8453 ) pstn send --> PSTNDisconnectCall() Trunk:2 Conn:0 BChannel:1 Reason:16 Loc:-1 Des:-1 rc:0 [Time: 12:36:43]
13:36:45.238 : 10.15.70.11 : NOTICE : ( lgr_flow)(8454 ) | #62:RELEASE_ACK (send) : (B0819C1D@10.32.3.171) [Time: 12:36:43]
13:36:45.238 : 10.15.70.11 : NOTICE : ( lgr_flow)(8455 ) | | #99:RELEASE_ACK:(B0819C1D@10.32.3.171) [Time: 12:36:43]
13:36:45.248 : 10.15.70.11 : NOTICE : ( lgr_flow)(8456 ) | | | #99:RELEASE_ACK(B0819C1D@10.32.3.171) [Time: 12:36:43]
13:36:45.248 : 10.15.70.11 : NOTICE : ( lgr_flow)(8457 ) | |(SIPTU#136)DISCONNECT_RESPONSE State:Disconnected(B0819C1D@10.32.3.171) [Time: 12:36:43]
13:36:45.258 : 10.15.70.11 : NOTICE : ( lgr_flow)(8458 ) ---- Outgoing SIP Message to 10.32.3.171:5060 ---- [Time: 12:36:43]
13:36:45.258 : 10.15.70.11 : NOTICE : SIP/2.0 200 OK Via: SIP/2.0/UDP 10.32.3.171;rport;branch=z9hG4bK+38970d0e5741df185fdcf00830022fdd+10.32.3.171+1 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e00001+b440bc4a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c196474796 Call-ID: B0819C1D@10.32.3.171 CSeq: 855103516 BYE Contact: <sip:61@10.15.70.11> Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-Mediant 2000/v.5.00A.044.003 Content-Length: 0 [Time: 12:36:43]
13:36:45.298 : 10.15.70.11 : NOTICE : ( sip_stack)(8460 ) AcSIPStackAPI::FreeCallAPI - #99 [Time: 12:36:43]
13:36:45.308 : 10.15.70.11 : NOTICE : ( sip_stack)(8461 ) Setting ApplicationCall of AcSIPCall 65036224 to NULL [Time: 12:36:43]
13:36:45.308 : 10.15.70.11 : NOTICE : ( lgr_stk_mngr)(8462 ) Resource StackSession <#99> Deleted [Time: 12:36:43]
13:36:45.318 : 10.15.70.11 : NOTICE : ( lgr_psbrdex)(8463 ) pstn recv <-- CALL_RELEASED Trunk:2 Conn:0 RetCause:104 NetCause:16 [Time: 12:36:43]
13:36:45.338 : 10.15.70.11 : NOTICE : ( lgr_flow)(8464 ) #62:LOCAL_CALL_RELEASED_EV(Trunk:2 Conn:0 Bchannel:1 TpEv=68) [Time: 12:36:43]
13:36:45.348 : 10.15.70.11 : NOTICE : ( lgr_flow)(8465 ) | #62:LOCAL_CALL_RELEASED_EV [Time: 12:36:43]
13:36:45.348 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8466 ) #62:CloseChannel: ChannelNum=62 [Time: 12:36:43]
13:36:50.098 : 10.15.70.11 : NOTICE : ( lgr_flow)(8467 ) | | TransactionUserMngr::ReturnSIPCall - #136 [Time: 12:36:48]
13:36:50.098 : 10.15.70.11 : NOTICE : ( sip_stack)(8468 ) SIPCall(#136) changes state from Disconnected to Idle [Time: 12:36:48]
13:40:51.666 : 10.15.70.11 : NOTICE : ( lgr_flow)(8469 ) ---- Incoming SIP Message from 10.32.3.171:5060 ---- [Time: 12:40:50]
13:40:51.676 : 10.15.70.11 : NOTICE : INVITE sip:079XXXXXXXX@10.15.70.11;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+a1f4a25e4810fd9a0e8daa31041da8cb+10.32.3.171+1 Allow-Events: message-summary Allow-Events: presence Allow-Events: refer Max-Forwards: 70 Call-ID: E50932BC@10.32.3.171 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e60001+599b242a To: <sip:079XXXXXXXX@10.15.70.11;user=phone> CSeq: 135653208 INVITE Expires: 180 Supported: replaces Supported: 100rel Contact: <sip:10.32.3.171> Remote-Party-ID: "Stu Reeves" <sip:7204101@10.32.3.171;user=phone>;party=calling;privacy=off;npi=1;ton=1 Content-Type: application/sdp Content-Length: 199 User-Agent: TeleVantage-UA/7.50.4961 v=0 o=TeleVantage 26 0 IN IP4 10.32.3.171 s=phone-call c=IN IP4 10.32.3.171 t=0 0 m=audio 6006 RTP/AVP 18 101 a=rtpmap:18 g729/8000/1 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000/1 [Time: 12:40:50]
13:40:51.707 : 10.15.70.11 : NOTICE : ( sip_stack)(8471 ) AcSIPParser: Problem in SIP Message Headers [Time: 12:40:50]
13:40:51.717 : 10.15.70.11 : WARNING : ( sip_stack)(8472 ) ?? [WARNING] AcSIPParser: Warning. Branch is too long. Truncated to 49 chars [Time: 12:40:50]
13:40:51.727 : 10.15.70.11 : WARNING : ( sip_stack)(8473 ) ?? [WARNING] Message type: INVITE [Time: 12:40:50]
13:40:51.737 : 10.15.70.11 : WARNING : ( sip_stack)(8474 ) ?? [WARNING] Source header: [Time: 12:40:50]
13:40:51.737 : 10.15.70.11 : WARNING : ( sip_stack)(8475 ) ?? [WARNING] Line: 2. Column: 37 [Time: 12:40:50]
13:40:51.747 : 10.15.70.11 : NOTICE : ( sip_stack)(8476 ) AcSIPParser: Unrecognized Header was detected at line: 3 [Time: 12:40:50]
13:40:51.757 : 10.15.70.11 : NOTICE : ( sip_stack)(8477 ) AcSIPParser: Unrecognized Header was detected at line: 4 [Time: 12:40:50]
13:40:51.767 : 10.15.70.11 : NOTICE : ( sip_stack)(8478 ) AcSIPParser: Unrecognized Header was detected at line: 5 [Time: 12:40:50]
13:40:51.787 : 10.15.70.11 : NOTICE : ( lgr_flow)(8479 ) | | new GetNewSIPCall created - #135 [Time: 12:40:50]
13:40:51.787 : 10.15.70.11 : NOTICE : ( sip_stack)(8480 ) new AcSIPCallAPI created - #98 [Time: 12:40:50]
13:40:51.797 : 10.15.70.11 : NOTICE : ( lgr_stk_mngr)(8481 ) Resource StackSession <#98> Allocated [Time: 12:40:50]
13:40:51.807 : 10.15.70.11 : NOTICE : ( lgr_flow)(8482 ) | |(SIPTU#135)INVITE State:Idle() [Time: 12:40:50]
13:40:51.817 : 10.15.70.11 : NOTICE : ( sip_stack)(8483 ) SIPCall(#135) changes state from Idle to Invited [Time: 12:40:50]
13:40:51.817 : 10.15.70.11 : NOTICE : ( lgr_flow)(8484 ) | | | #98:SIP_SETUP_EV(E50932BC@10.32.3.171) [Time: 12:40:50]
13:40:51.827 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8485 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 [Time: 12:40:50]
13:40:51.837 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8486 ) <SESSION #98> SendToCall - event: NEW_CALL_EV m_Call = 35530752 [Time: 12:40:50]
13:40:51.847 : 10.15.70.11 : NOTICE : ( lgr_flow)(8487 ) | | #98:NEW_CALL_EV:(E50932BC@10.32.3.171) [Time: 12:40:50]
13:40:51.847 : 10.15.70.11 : NOTICE : ( lgr_flow)(8488 ) | | #98:Call changing states from:IdleState to:NewCallState_IP2Tel [Time: 12:40:50]
13:40:51.857 : 10.15.70.11 : NOTICE : ( lgr_flow)(8489 ) ServicesMngr::GetEndPoint PhoneNum = 079XXXXXXXX [Time: 12:40:50]
13:40:51.867 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8490 ) GetTrunkGroupId- TrunkGroup:2 found DstNum:079XXXXXXXX DstPfx:* SrcNum:7204101 SrcPfx:* SrcIp:a2003ab SrcIpPfx:* [Time: 12:40:50]
13:40:51.877 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8491 ) Current trunks status: 1110 [Time: 12:40:50]
13:40:51.887 : 10.15.70.11 : NOTICE : ( lgr_profiling)(8492 ) CIpProfile::GetProfileIdFromIpToTelTable DNIS:079XXXXXXXX,ANI:7204101,DestAddress:10.32.3.171 :Profile Found <2> [Time: 12:40:50]
13:40:51.897 : 10.15.70.11 : NOTICE : ( lgr_profiling)(8493 ) <Call 98> Profiled<Tel=2,Ip=2>: TelCoderGrId=2 IpCoderGrId=2 JBMinDel=70 JBOptF=10 EEarlyM=1 FaxTM=2 IPDS=46 IsFaxU=2 PI2IP=-1 SigIPDF=40 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 RTPRD=0 SCE=0 VxxTT=2 DTMFVol=20 ECE=1 ECurDis=0 EDigDel=0 ERevP=0 FHPer=400 InG=37 MWIA=0 MWID=0 VVol=37 [Time: 12:40:50]
13:40:51.897 : 10.15.70.11 : NOTICE : ( lgr_flow)(8494 ) | #62:NEW_CALL_EV : (E50932BC@10.32.3.171) [Time: 12:40:50]
13:40:51.907 : 10.15.70.11 : NOTICE : ( sip_stack)(8495 ) SIPSDPSession#98 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED [Time: 12:40:50]
13:40:51.917 : 10.15.70.11 : NOTICE : ( lgr_flow)(8496 ) | |(SIPTU#135)TRYING_REQ State:Invited(E50932BC@10.32.3.171) [Time: 12:40:50]
13:40:51.927 : 10.15.70.11 : NOTICE : ( lgr_flow)(8497 ) ---- Outgoing SIP Message to 10.32.3.171:5060 ---- [Time: 12:40:50]
13:40:51.927 : 10.15.70.11 : NOTICE : SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+a1f4a25e4810fd9a0e8daa31041da8cb+10.32.3.171+1 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e60001+599b242a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c235654284 Call-ID: E50932BC@10.32.3.171 CSeq: 135653208 INVITE Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-Mediant 2000/v.5.00A.044.003 Content-Length: 0 [Time: 12:40:50]
13:40:51.987 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8499 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 [Time: 12:40:50]
13:40:51.997 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8500 ) <SESSION #98> SendToCall - event: SETUP m_Call = 35530752 [Time: 12:40:50]
13:40:51.997 : 10.15.70.11 : NOTICE : ( lgr_flow)(8501 ) | | #98:SETUP (TO:079XXXXXXXX, FROM:7204101):(E50932BC@10.32.3.171) [Time: 12:40:50]
13:40:52.007 : 10.15.70.11 : NOTICE : ( lgr_flow)(8502 ) | | #98:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel [Time: 12:40:50]
13:40:52.007 : 10.15.70.11 : NOTICE : ( lgr_flow)(8503 ) | #62:SETUP : (E50932BC@10.32.3.171) [Time: 12:40:50]
13:40:52.017 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8504 ) pstn send --> PlaceCall: Trunk:2 BChannel:1 ConnID:0 SrcPN=7204101 SrcSN= DstPN=079XXXXXXXX DstSN= SrcNT=1 SrcNP=1 SrcPres=0 SrcScrn=0 DstNT=0 DstNP=0 ServiceCap=M RdrctNum= RdNT=0 RdNP=0 RdPres=0 RdScrn=0 RdRsn=-1 Excl=1 Display=Stu Reeves IE= UUIE=0,, CLIRReason:-1 [Time: 12:40:50]
13:40:52.017 : 10.15.70.11 : NOTICE : ( lgr_psbrdex)(8505 ) pstn recv <-- CALL_PROCEEDING Trunk:2 Conn:0 BChannel:1 callhndl:0 Loc:-1 Des:-1 [Time: 12:40:50]
13:40:52.027 : 10.15.70.11 : NOTICE : ( lgr_flow)(8506 ) #62:LOCAL_CALL_PROCEEDING_EV(Trunk:2 Conn:0 Bchannel:1 TpEv=73) [Time: 12:40:50]
13:40:52.027 : 10.15.70.11 : NOTICE : ( lgr_flow)(8507 ) | #62:LOCAL_CALL_PROCEEDING_EV [Time: 12:40:50]
13:40:52.027 : 10.15.70.11 : NOTICE : ( lgr_flow)(8508 ) | #62:PROCEEDING (send) : (E50932BC@10.32.3.171) [Time: 12:40:50]
13:40:52.037 : 10.15.70.11 : NOTICE : ( lgr_flow)(8509 ) | | #98:PROCEEDING:(E50932BC@10.32.3.171) [Time: 12:40:50]
13:40:52.037 : 10.15.70.11 : NOTICE : ( lgr_flow)(8510 ) | | | #98:PROCEEDING(E50932BC@10.32.3.171) [Time: 12:40:50]
13:40:58.400 : 10.15.70.11 : NOTICE : ( lgr_psbrdex)(8511 ) pstn recv <-- CALL_ALERTING Trunk:2 Conn:0 BChannel:1 [Time: 12:40:56]
13:40:58.420 : 10.15.70.11 : NOTICE : ( lgr_flow)(8512 ) #62:LOCAL_ALERTING_EV(Trunk:2 Conn:0 Bchannel:1 TpEv=69) [Time: 12:40:56]
13:40:58.430 : 10.15.70.11 : NOTICE : ( lgr_flow)(8513 ) | #62:LOCAL_ALERTING_EV [Time: 12:40:56]
13:40:58.440 : 10.15.70.11 : NOTICE : ( lgr_flow)(8514 ) | #62:ALERT (send) : (E50932BC@10.32.3.171) [Time: 12:40:56]
13:40:58.440 : 10.15.70.11 : NOTICE : ( lgr_flow)(8515 ) | | #98:ALERT:(E50932BC@10.32.3.171) [Time: 12:40:56]
13:40:58.450 : 10.15.70.11 : NOTICE : ( lgr_flow)(8516 ) | | #98:Call changing states from:InitiatedState_IP2Tel to:AlertingState_IP2Tel [Time: 12:40:56]
13:40:58.460 : 10.15.70.11 : NOTICE : ( lgr_flow)(8517 ) | | | #98:ALERT(E50932BC@10.32.3.171) [Time: 12:40:56]
13:40:58.470 : 10.15.70.11 : NOTICE : ( lgr_flow)(8518 ) | |(SIPTU#135)ALERT_REQ State:Invited(E50932BC@10.32.3.171) [Time: 12:40:56]
13:40:58.470 : 10.15.70.11 : NOTICE : ( lgr_flow)(8519 ) ---- Outgoing SIP Message to 10.32.3.171:5060 ---- [Time: 12:40:56]
13:40:58.480 : 10.15.70.11 : NOTICE : SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+a1f4a25e4810fd9a0e8daa31041da8cb+10.32.3.171+1 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e60001+599b242a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c235654284 Call-ID: E50932BC@10.32.3.171 CSeq: 135653208 INVITE Contact: <sip:61@10.15.70.11> Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Require: 100rel RSeq: 1 Server: Audiocodes-Sip-Gateway-Mediant 2000/v.5.00A.044.003 Content-Length: 0 [Time: 12:40:56]
13:40:58.550 : 10.15.70.11 : NOTICE : ( sip_stack)(8521 ) UdpRtxMngr::Transmit 180 Response 135653208 INVITE Rtx Left: 6 Dest: a2003ab:5060 [Time: 12:40:56]
13:40:58.560 : 10.15.70.11 : NOTICE : ( sip_stack)(8522 ) SIPTransaction::ResendLastMessage - Resending last message [Time: 12:40:56]
13:40:58.570 : 10.15.70.11 : NOTICE : ( lgr_flow)(8523 ) ---- Incoming SIP Message from 10.32.3.171:5060 ---- [Time: 12:40:56]
13:40:58.580 : 10.15.70.11 : NOTICE : PRACK sip:079XXXXXXXX@10.15.70.11;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+d08e5369cb5aaacaf62a002dd8aaff05+10.32.3.171+1 Allow-Events: message-summary Allow-Events: presence Allow-Events: refer Max-Forwards: 70 Call-ID: E50932BC@10.32.3.171 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e60001+599b242a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c235654284 CSeq: 135653209 PRACK RAck: 1 135653208 INVITE Supported: replaces Supported: 100rel Content-Length: 0 User-Agent: TeleVantage-UA/7.50.4961 [Time: 12:40:56]
13:40:58.630 : 10.15.70.11 : NOTICE : ( sip_stack)(8525 ) AcSIPParser: Problem in SIP Message Headers [Time: 12:40:56]
13:40:58.630 : 10.15.70.11 : WARNING : ( sip_stack)(8526 ) ?? [WARNING] AcSIPParser: Warning. Branch is too long. Truncated to 49 chars [Time: 12:40:56]
13:40:58.640 : 10.15.70.11 : WARNING : ( sip_stack)(8527 ) ?? [WARNING] Message type: PRACK [Time: 12:40:56]
13:40:58.640 : 10.15.70.11 : WARNING : ( sip_stack)(8528 ) ?? [WARNING] Source header: [Time: 12:40:56]
13:40:58.640 : 10.15.70.11 : WARNING : ( sip_stack)(8529 ) ?? [WARNING] Line: 2. Column: 37 [Time: 12:40:56]
13:40:58.650 : 10.15.70.11 : NOTICE : ( sip_stack)(8530 ) AcSIPParser: Unrecognized Header was detected at line: 3 [Time: 12:40:56]
13:40:58.650 : 10.15.70.11 : NOTICE : ( sip_stack)(8531 ) AcSIPParser: Unrecognized Header was detected at line: 4 [Time: 12:40:56]
13:40:58.660 : 10.15.70.11 : NOTICE : ( sip_stack)(8532 ) AcSIPParser: Unrecognized Header was detected at line: 5 [Time: 12:40:56]
13:40:58.670 : 10.15.70.11 : NOTICE : ( lgr_flow)(8533 ) | |(SIPTU#135)PRACK State:Invited(E50932BC@10.32.3.171) [Time: 12:40:56]
13:40:58.670 : 10.15.70.11 : NOTICE : ( sip_stack)(8534 ) UdpRtxMngr::Remove 180 Response 135653208 INVITE [Time: 12:40:56]
13:40:58.670 : 10.15.70.11 : NOTICE : ( lgr_flow)(8535 ) ---- Outgoing SIP Message to 10.32.3.171:5060 ---- [Time: 12:40:56]
13:40:58.680 : 10.15.70.11 : NOTICE : SIP/2.0 200 OK Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+d08e5369cb5aaacaf62a002dd8aaff05+10.32.3.171+1 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e60001+599b242a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c235654284 Call-ID: E50932BC@10.32.3.171 CSeq: 135653209 PRACK Contact: <sip:61@10.15.70.11> Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-Mediant 2000/v.5.00A.044.003 Content-Length: 0 [Time: 12:40:56]
13:40:58.710 : 10.15.70.11 : NOTICE : ( lgr_flow)(8537 ) | | | #98:SIP_PRACK_RESPONDED_EV(E50932BC@10.32.3.171) [Time: 12:40:56]
13:41:04.151 : 10.15.70.11 : NOTICE : ( lgr_psbrdex)(8538 ) pstn recv <-- CALL_CONNECTED Trunk:2 Conn:0 BChannel:1 Number: [Time: 12:41:2]
13:41:04.181 : 10.15.70.11 : NOTICE : ( lgr_flow)(8539 ) #62:LOCAL_CALL_CONNECTED_EV(Trunk:2 Conn:0 Bchannel:1 TpEv=65) [Time: 12:41:2]
13:41:04.181 : 10.15.70.11 : NOTICE : ( lgr_flow)(8540 ) | #62:LOCAL_CALL_CONNECTED_EV [Time: 12:41:2]
13:41:04.191 : 10.15.70.11 : NOTICE : ( lgr_flow)(8541 ) | #62:OPEN_VOICE-NOT_READY (MS:0, HS:0, Ready:1) [Time: 12:41:2]
13:41:04.191 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8542 ) #62:PSOSBoardInterface::StopPlayTone- Called [Time: 12:41:2]
13:41:04.201 : 10.15.70.11 : NOTICE : ( lgr_flow)(8543 ) | #62:CONNECT (send) : (E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:04.201 : 10.15.70.11 : NOTICE : ( lgr_flow)(8544 ) | | #98:CONNECT:(E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:04.211 : 10.15.70.11 : NOTICE : ( lgr_flow)(8545 ) | | #98:Call changing states from:AlertingState_IP2Tel to:ConnectedState [Time: 12:41:2]
13:41:04.211 : 10.15.70.11 : NOTICE : ( lgr_flow)(8546 ) | | | #98:CONNECT(E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:04.221 : 10.15.70.11 : NOTICE : ( sip_stack)(8547 ) SIPSDPSession#98 - Changing state from SIP_MEDIA_OFFERED to SIP_MEDIA_COMPLETED [Time: 12:41:2]
13:41:04.231 : 10.15.70.11 : NOTICE : ( lgr_flow)(8548 ) | |(SIPTU#135)CONNECT_REQ State:Invited(E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:04.231 : 10.15.70.11 : NOTICE : ( lgr_flow)(8549 ) ---- Outgoing SIP Message to 10.32.3.171:5060 ---- [Time: 12:41:2]
13:41:04.241 : 10.15.70.11 : NOTICE : SIP/2.0 200 OK Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+a1f4a25e4810fd9a0e8daa31041da8cb+10.32.3.171+1 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e60001+599b242a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c235654284 Call-ID: E50932BC@10.32.3.171 CSeq: 135653208 INVITE Contact: <sip:61@10.15.70.11> Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-Mediant 2000/v.5.00A.044.003 Content-Type: application/sdp Content-Length: 252 v=0 o=AudiocodesGW 235733186 235732879 IN IP4 10.15.70.11 s=Phone-Call c=IN IP4 10.15.70.11 t=0 0 m=audio 6620 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv [Time: 12:41:2]
13:41:04.342 : 10.15.70.11 : NOTICE : ( sip_stack)(8551 ) UdpRtxMngr::Transmit 200 Response 135653208 INVITE Rtx Left: 6 Dest: a2003ab:5060 [Time: 12:41:2]
13:41:04.362 : 10.15.70.11 : NOTICE : ( sip_stack)(8552 ) SIPTransaction::ResendLastMessage - Resending last message [Time: 12:41:2]
13:41:04.362 : 10.15.70.11 : NOTICE : ( sip_stack)(8553 ) SIPCall(#135) changes state from Invited to LocalAccepted [Time: 12:41:2]
13:41:04.372 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8554 ) DtmfCapNegotiationAlgorithm :: TxDtmfMethod = DTMF_RFC2833_SUPPORTED [Time: 12:41:2]
13:41:04.382 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8555 ) DtmfCapNegotiationAlgorithm :: TxRtpRfc2833Payload = 101 [Time: 12:41:2]
13:41:04.382 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8556 ) <SESSION #98> SendToCall - event: DTMF_CONTROL_EV m_Call = 35530752 [Time: 12:41:2]
13:41:04.392 : 10.15.70.11 : NOTICE : ( lgr_flow)(8557 ) | | #98:DTMF_CONTROL_EV:(E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:04.392 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8558 ) <SESSION #98> SendToCall - event: OPEN_LOGICAL_CHANNEL_ACK m_Call = 35530752 [Time: 12:41:2]
13:41:04.402 : 10.15.70.11 : NOTICE : ( lgr_flow)(8559 ) | | #98:OPEN_LOGICAL_CHANNEL_ACK:(E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:04.402 : 10.15.70.11 : NOTICE : ( lgr_flow)(8560 ) | #62:OPEN_LOGICAL_CHANNEL_ACK : (E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:04.402 : 10.15.70.11 : NOTICE : ( lgr_flow)(8561 ) | #62:OPEN_VOICE (IP:a2003ab, RTP:6006, RTCP:6007, VoiceCoder:g72920, VbdCoder: InvalidCoder255, Dtmf:gwRFC2833RalayDTMF,Rx payload:101,Tx payload:101 ,RTPmode:1, FaxTransportType: 3) [Time: 12:41:2]
13:41:04.412 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8562 ) #62:ActivateChannel: Socks=15 CID=62 Trunk:2 BChannel:1 RemoteIP=a2003ab RemotePort=6006 RemoteRTCPIP=a2003ab RemoteRTCPPort=6007 FaxModemDet=NO_FAX_MODEM_DETECTED [Time: 12:41:2]
13:41:04.412 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8563 ) #62:OpenChannel:on Trunk 2 BChannel:1 CID=62 with VoiceCoder: g72920 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 12:41:2]
13:41:04.412 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8564 ) #62:OpenChannel VoiceVolume= 5, DTMFVolume = -11, InputGain = 5, RTPRedundancyDepth = 0 FlashHookPeriod = 400 [Time: 12:41:2]
13:41:04.422 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8565 ) Detectors: Amd:0, Ans:0 En:0 IBScmd:0xa1 [Time: 12:41:2]
13:41:04.422 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8566 ) OpenChannel, CoderType = 17, Interval = 3, M = 1 [Time: 12:41:2]
13:41:04.422 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8567 ) #62:FAXTransportType = 3 [Time: 12:41:2]
13:41:04.432 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8568 ) #62:PSOSBoardInterface::ConfigureFaxModemChannelParams FAXTransportType=3 Modem configuration VxxTransportType=2 not allowed, forced to 3 [Time: 12:41:2]
13:41:04.432 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8569 ) #62:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=3, VxxTranType=3, VoiceVol= 5, DTMFVol=-11, InGain=5, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=70, DJBufOptFac=10) [Time: 12:41:2]
13:41:04.442 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8570 ) Setting ActivateRTP_RTCPCmd.Cmd.IpTosFieldInUdpPacket to 184 [Time: 12:41:2]
13:41:04.442 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8571 ) #62:ActivateChannel:RtpPayload: 18 [Time: 12:41:2]
13:41:04.442 : 10.15.70.11 : NOTICE : ( lgr_flow)(8572 ) ---- Incoming SIP Message from 10.32.3.171:5060 ---- [Time: 12:41:2]
13:41:04.452 : 10.15.70.11 : NOTICE : ACK sip:61@10.15.70.11 SIP/2.0 Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+14d771796707fb2197e72165af5a75bf+10.32.3.171+1 Allow-Events: message-summary Allow-Events: presence Allow-Events: refer Max-Forwards: 70 Call-ID: E50932BC@10.32.3.171 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e60001+599b242a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c235654284 CSeq: 135653208 ACK Contact: <sip:10.32.3.171> Content-Length: 0 User-Agent: TeleVantage-UA/7.50.4961 [Time: 12:41:2]
13:41:04.482 : 10.15.70.11 : NOTICE : ( sip_stack)(8574 ) AcSIPParser: Problem in SIP Message Headers [Time: 12:41:2]
13:41:04.482 : 10.15.70.11 : WARNING : ( sip_stack)(8575 ) ?? [WARNING] AcSIPParser: Warning. Branch is too long. Truncated to 49 chars [Time: 12:41:2]
13:41:04.492 : 10.15.70.11 : WARNING : ( sip_stack)(8576 ) ?? [WARNING] Message type: ACK [Time: 12:41:2]
13:41:04.492 : 10.15.70.11 : WARNING : ( sip_stack)(8577 ) ?? [WARNING] Source header: [Time: 12:41:2]
13:41:04.492 : 10.15.70.11 : WARNING : ( sip_stack)(8578 ) ?? [WARNING] Line: 2. Column: 37 [Time: 12:41:2]
13:41:04.502 : 10.15.70.11 : NOTICE : ( sip_stack)(8579 ) AcSIPParser: Unrecognized Header was detected at line: 3 [Time: 12:41:2]
13:41:04.502 : 10.15.70.11 : NOTICE : ( sip_stack)(8580 ) AcSIPParser: Unrecognized Header was detected at line: 4 [Time: 12:41:2]
13:41:04.512 : 10.15.70.11 : NOTICE : ( sip_stack)(8581 ) AcSIPParser: Unrecognized Header was detected at line: 5 [Time: 12:41:2]
13:41:04.512 : 10.15.70.11 : NOTICE : ( sip_stack)(8582 ) UdpRtxMngr::Remove 200 Response 135653208 INVITE [Time: 12:41:2]
13:41:04.522 : 10.15.70.11 : NOTICE : ( lgr_flow)(8583 ) | |(SIPTU#135)ACK State:LocalAccepted(E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:04.522 : 10.15.70.11 : NOTICE : ( sip_stack)(8584 ) SIPCall(#135) changes state from LocalAccepted to Connected [Time: 12:41:2]
13:41:04.522 : 10.15.70.11 : NOTICE : ( lgr_flow)(8585 ) | | | #98:SIP_ACK_EV(E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:04.532 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8586 ) <SESSION #98> SendToCall - event: CONNECT_ACK_EV m_Call = 35530752 [Time: 12:41:2]
13:41:04.532 : 10.15.70.11 : NOTICE : ( lgr_flow)(8587 ) | | #98:CONNECT_ACK_EV:(E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:04.532 : 10.15.70.11 : NOTICE : ( lgr_flow)(8588 ) | #62:CONNECT_ACK_EV : (E50932BC@10.32.3.171) [Time: 12:41:2]
13:41:07.077 : 10.15.70.11 : NOTICE : ( lgr_flow)(8589 ) ---- Incoming SIP Message from 10.32.3.171:5060 ---- [Time: 12:41:5]
13:41:07.087 : 10.15.70.11 : NOTICE : BYE sip:61@10.15.70.11 SIP/2.0 Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+aa9d7ea7707c5485d60a8a1f8976bef5+10.32.3.171+1 Allow-Events: message-summary Allow-Events: presence Allow-Events: refer Max-Forwards: 70 Call-ID: E50932BC@10.32.3.171 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e60001+599b242a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c235654284 CSeq: 135653210 BYE Supported: replaces Supported: 100rel Content-Length: 0 User-Agent: TeleVantage-UA/7.50.4961 [Time: 12:41:5]
13:41:07.157 : 10.15.70.11 : NOTICE : ( sip_stack)(8591 ) AcSIPParser: Problem in SIP Message Headers [Time: 12:41:5]
13:41:07.167 : 10.15.70.11 : WARNING : ( sip_stack)(8592 ) ?? [WARNING] AcSIPParser: Warning. Branch is too long. Truncated to 49 chars [Time: 12:41:5]
13:41:07.177 : 10.15.70.11 : WARNING : ( sip_stack)(8593 ) ?? [WARNING] Message type: BYE [Time: 12:41:5]
13:41:07.177 : 10.15.70.11 : WARNING : ( sip_stack)(8594 ) ?? [WARNING] Source header: [Time: 12:41:5]
13:41:07.187 : 10.15.70.11 : WARNING : ( sip_stack)(8595 ) ?? [WARNING] Line: 2. Column: 37 [Time: 12:41:5]
13:41:07.197 : 10.15.70.11 : NOTICE : ( sip_stack)(8596 ) AcSIPParser: Unrecognized Header was detected at line: 3 [Time: 12:41:5]
13:41:07.207 : 10.15.70.11 : NOTICE : ( sip_stack)(8597 ) AcSIPParser: Unrecognized Header was detected at line: 4 [Time: 12:41:5]
13:41:07.217 : 10.15.70.11 : NOTICE : ( sip_stack)(8598 ) AcSIPParser: Unrecognized Header was detected at line: 5 [Time: 12:41:5]
13:41:07.227 : 10.15.70.11 : NOTICE : ( lgr_flow)(8599 ) | |(SIPTU#135)BYE State:Connected(E50932BC@10.32.3.171) [Time: 12:41:5]
13:41:07.227 : 10.15.70.11 : NOTICE : ( sip_stack)(8600 ) SIPCall(#135) changes state from Connected to Disconnected [Time: 12:41:5]
13:41:07.237 : 10.15.70.11 : NOTICE : ( lgr_flow)(8601 ) | | | #98:SIP_DISCONNECT_EV(E50932BC@10.32.3.171) [Time: 12:41:5]
13:41:07.247 : 10.15.70.11 : NOTICE : ( lgr_stk_ses)(8602 ) <SESSION #98> SendToCall - event: RELEASE m_Call = 35530752 [Time: 12:41:5]
13:41:07.257 : 10.15.70.11 : NOTICE : ( lgr_flow)(8603 ) | | #98:RELEASE:(E50932BC@10.32.3.171) [Time: 12:41:5]
13:41:07.257 : 10.15.70.11 : NOTICE : ( lgr_flow)(8604 ) | | #98:Call changing states from:ConnectedState to:DisconnectingState [Time: 12:41:5]
13:41:07.267 : 10.15.70.11 : NOTICE : ( lgr_flow)(8605 ) | | #98:RELEASE_ACK:(E50932BC@10.32.3.171) [Time: 12:41:5]
13:41:07.277 : 10.15.70.11 : NOTICE : ( lgr_flow)(8606 ) | #62:RELEASE GWAPP_NORMAL_CALL_CLEAR : (E50932BC@10.32.3.171) [Time: 12:41:5]
13:41:07.277 : 10.15.70.11 : NOTICE : ( lgr_flow)(8607 ) | #62:Close voice Channel [Time: 12:41:5]
13:41:07.287 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8608 ) #62:StopRTP_RTCP on channel 62 [Time: 12:41:5]
13:41:07.287 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8609 ) pstn send --> PSTNDisconnectCall() Trunk:2 Conn:0 BChannel:1 Reason:16 Loc:-1 Des:-1 rc:0 [Time: 12:41:5]
13:41:07.297 : 10.15.70.11 : NOTICE : ( lgr_flow)(8610 ) | #62:RELEASE_ACK (send) : (E50932BC@10.32.3.171) [Time: 12:41:5]
13:41:07.307 : 10.15.70.11 : NOTICE : ( lgr_flow)(8611 ) | | #98:RELEASE_ACK:(E50932BC@10.32.3.171) [Time: 12:41:5]
13:41:07.307 : 10.15.70.11 : NOTICE : ( lgr_flow)(8612 ) | | | #98:RELEASE_ACK(E50932BC@10.32.3.171) [Time: 12:41:5]
13:41:07.317 : 10.15.70.11 : NOTICE : ( lgr_flow)(8613 ) | |(SIPTU#135)DISCONNECT_RESPONSE State:Disconnected(E50932BC@10.32.3.171) [Time: 12:41:5]
13:41:07.327 : 10.15.70.11 : NOTICE : ( lgr_flow)(8614 ) ---- Outgoing SIP Message to 10.32.3.171:5060 ---- [Time: 12:41:5]
13:41:07.327 : 10.15.70.11 : NOTICE : SIP/2.0 200 OK Via: SIP/2.0/UDP 10.32.3.171;branch=z9hG4bK+aa9d7ea7707c5485d60a8a1f8976bef5+10.32.3.171+1 From: "Stu Reeves"<sip:7204101@10.32.3.171;user=phone>;tag=10.32.3.171+1+1e60001+599b242a To: <sip:079XXXXXXXX@10.15.70.11;user=phone>;tag=1c235654284 Call-ID: E50932BC@10.32.3.171 CSeq: 135653210 BYE Contact: <sip:61@10.15.70.11> Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-Mediant 2000/v.5.00A.044.003 Content-Length: 0 [Time: 12:41:5]
13:41:07.388 : 10.15.70.11 : NOTICE : ( sip_stack)(8616 ) AcSIPStackAPI::FreeCallAPI - #98 [Time: 12:41:5]
13:41:07.398 : 10.15.70.11 : NOTICE : ( sip_stack)(8617 ) Setting ApplicationCall of AcSIPCall 65028168 to NULL [Time: 12:41:5]
13:41:07.398 : 10.15.70.11 : NOTICE : ( lgr_stk_mngr)(8618 ) Resource StackSession <#98> Deleted [Time: 12:41:5]
13:41:07.408 : 10.15.70.11 : NOTICE : ( lgr_psbrdex)(8619 ) pstn recv <-- CALL_RELEASED Trunk:2 Conn:0 RetCause:104 NetCause:16 [Time: 12:41:5]
13:41:07.408 : 10.15.70.11 : NOTICE : ( lgr_flow)(8620 ) #62:LOCAL_CALL_RELEASED_EV(Trunk:2 Conn:0 Bchannel:1 TpEv=68) [Time: 12:41:5]
13:41:07.418 : 10.15.70.11 : NOTICE : ( lgr_flow)(8621 ) | #62:LOCAL_CALL_RELEASED_EV [Time: 12:41:5]
13:41:07.418 : 10.15.70.11 : NOTICE : ( lgr_psbrdif)(8622 ) #62:CloseChannel: ChannelNum=62 [Time: 12:41:5]
13:41:12.127 : 10.15.70.11 : NOTICE : ( lgr_flow)(8623 ) | | TransactionUserMngr::ReturnSIPCall - #135 [Time: 12:41:10]
13:41:12.137 : 10.15.70.11 : NOTICE : ( sip_stack)(8624 ) SIPCall(#135) changes state from Disconnected to Idle [Time: 12:41:10]
13:58:29.988 : 10.15.70.11 : INFO : NTP Reference time 10-13-2008@12:58:27 [Time: 12:58:27]
13:58:29.998 : 10.15.70.11 : DEBUG : [PType=9] Seconds -862075787: 0 1223902709 [File:acNTP.cpp] [Time: 12:58:27]
13:58:29.998 : 10.15.70.11 : INFO : [PType=9] DateBuffer 10-13-2008@12:58:29 [Time: 12:58:29]
13:58:29.998 : 10.15.70.11 : DEBUG : [PType=9] NTP:doNTPWaitForNTPCB [File:acNTP.cpp] [Time: 12:58:29]
13:58:34.026 : 10.15.70.11 : NOTICE : ( lgr_psbrdex)(8625 ) recv <-- UnHandled event: 287 [Time: 12:58:33]Most people spend their time on the "urgent" rather than on the "important."