# 2006-01-09 # Caller ID: # Look at (591 ) # Start of Call from 9724090813 and the PSTN switch provided "A B P INTL " as # the Caller ID Name bewteen the 1st and 2nd rings. # DTMF with RFC2833: # Look at (692 ) # The MP realized that the I(From a PSTN phone) hit the digit 2. # Look at (693 ) # The MP send those digits in an RFC2833 packet to the * server. # Look at (698 ) # It actually does this twice. Maybe turn off or something but I don't have time to read up why. # Look at 9d:15h:2m:51s # This is where my wife hit a digit on her POTS phone connected to my Asterisk box via a TDM11B card. # But the Audiocodes complains of a duplicated packet. # From that POTS phone, we can still dial out and get through all the bank IVRs that I can find, # Will have to get RTP traces and see what is actually happening, but my wife is tiring of answering the phone. Log is Activated 9d:15h:2m:11s ( lgr_psbrdex)(584 ) recv <-- ANALOG_IF_RING_START Ch:0 [Time: 15:2:11] 9d:15h:2m:11s ( lgr_flow)(585 ) #0:RING_START_EV [Time: 15:2:11] 9d:15h:2m:11s ( lgr_flow)(586 ) | #0:RING_START_EV [Time: 15:2:11] 9d:15h:2m:11s ( lgr_psbrdex)(587 ) recv <-- EV_ANALOG_IF_RING_END Ch:0 [Time: 15:2:11] 9d:15h:2m:11s ( lgr_flow)(588 ) #0:RING_END_EV [Time: 15:2:11] 9d:15h:2m:11s ( lgr_flow)(589 ) | #0:RING_END_EV [Time: 15:2:11] 9d:15h:2m:13s DID Detected - Called Number: 15138748 [CID:0] [Time: 15:2:13] 9d:15h:2m:13s ( lgr_psbrdex)(590 ) recv <-- EV_DETECT_CALLER_ID CID:0 (Name=A B P INTL , Number=9724090813) [Time: 15:2:13] 9d:15h:2m:13s ( lgr_flow)(591 ) #0:CALLER_ID_DETECTED_EV [Time: 15:2:13] 9d:15h:2m:13s ( lgr_flow)(592 ) | #0:CALLER_ID_DETECTED_EV [Time: 15:2:13] 9d:15h:2m:13s ( lgr_flow)(593 ) | #0:CALLER_ID: Name=A B P INTL , PhoneNumber=9724090813 Enabled=1 [Time: 15:2:13] 9d:15h:2m:16s ( lgr_psbrdex)(594 ) recv <-- ANALOG_IF_RING_START Ch:0 [Time: 15:2:16] 9d:15h:2m:16s ( lgr_flow)(595 ) #0:RING_START_EV [Time: 15:2:16] 9d:15h:2m:16s ( lgr_flow)(596 ) | #0:RING_START_EV [Time: 15:2:16] 9d:15h:2m:17s ( lgr_psbrdex)(597 ) recv <-- EV_ANALOG_IF_RING_END Ch:0 [Time: 15:2:17] 9d:15h:2m:17s ( lgr_flow)(598 ) #0:RING_END_EV [Time: 15:2:17] 9d:15h:2m:17s ( lgr_flow)(599 ) | #0:RING_END_EV [Time: 15:2:17] 9d:15h:2m:22s ( lgr_psbrdex)(600 ) recv <-- ANALOG_IF_RING_START Ch:0 [Time: 15:2:22] 9d:15h:2m:22s ( lgr_flow)(601 ) #0:RING_START_EV [Time: 15:2:22] 9d:15h:2m:22s ( lgr_flow)(602 ) | #0:RING_START_EV [Time: 15:2:22] 9d:15h:2m:23s ( lgr_psbrdex)(603 ) recv <-- EV_ANALOG_IF_RING_END Ch:0 [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(604 ) #0:RING_END_EV [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(605 ) | #0:RING_END_EV [Time: 15:2:23] 9d:15h:2m:23s ( lgr_psbrdif)(606 ) #0:cpDigitMapHndlr_Stop - Stoped (0) [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(607 ) | #0:NEW_CALL_EV (send) : (UnKnown) [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(608 ) | | #0:NEW_CALL_EV:(UnKnown) [Time: 15:2:23] 9d:15h:2m:23s ( lgr_stk_mngr)(609 ) Resource StackSession <#0> Allocated [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(610 ) | | | #0:NEW_CALL_EV(UNKnown) [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(611 ) | (to 200) [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(612 ) | #0:SETUP (send) : (UnKnown) [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(613 ) | | #0:SETUP (TO:200, FROM:9724090813):(UnKnown) [Time: 15:2:23] 9d:15h:2m:23s ( lgr_stk_ses)(614 ) SIPStackSession::GetPrefixSearchFormat, ProxyWorking = 1, ReplaceReason = 0, SENDINVITE2PROXY = 1 [Time: 15:2:23] 9d:15h:2m:23s ( lgr_profiling)(615 ) CIpProfile::GetProfileIdFromIpToTelTable DNIS:200,ANI:9724090813,DestAddress:0.0.0.0 :Profile Found <0> [Time: 15:2:23] 9d:15h:2m:23s ( lgr_profiling)(616 ) CTelProfile::GetCodersByProfileByID :Profile Not Found, Use Default Configuration [Time: 15:2:23] 9d:15h:2m:23s ( lgr_profiling)(617 ) CIpProfile::GetCodersByProfileByID :Get Coder List [Time: 15:2:23] 9d:15h:2m:23s ( lgr_profiling)(618 ) CIpProfile::GetCodersByProfileByID :Profile Not Found, Use Default Coders List [Time: 15:2:23] 9d:15h:2m:23s ( lgr_profiling)(619 ) CProfile::GetCoderList : Coders Group 0 [Time: 15:2:23] 9d:15h:2m:23s ( lgr_profiling)(620 ) CTelProfile::GetCodersByProfileByID :Get Coder List [Time: 15:2:23] 9d:15h:2m:23s ( lgr_profiling)(621 ) CTelProfile::GetCodersByProfileByID :Profile Not Found, Use Default Coders List [Time: 15:2:23] 9d:15h:2m:23s ( lgr_profiling)(622 ) CProfile::GetCoderList : Coders Group 0 [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(623 ) | | | #0:SETUP(UNKnown) [Time: 15:2:23] 9d:15h:2m:23s ( sip_stack)(624 ) new AcSIPCallAPI created - #0 [Time: 15:2:23] 9d:15h:2m:23s ( sip_stack)(625 ) new GetNewSIPCall created - #0 [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(626 ) | |(SIPTU#0)SETUP_REQ State:Idle() [Time: 15:2:23] 9d:15h:2m:23s ( sip_stack)(627 ) SIPCall(#0) changes state from Idle to Inviting [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(628 ) ---- Outgoing SIP Message to 192.168.2.10:5060 ---- [Time: 15:2:23] 9d:15h:2m:23s INVITE sip:200@192.168.2.10;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.2.12;branch=z9hG4bKac1319844491 Max-Forwards: 70 From: "A B P INTL " ;tag=1c1319842710 To: Call-ID: 131984243791200615223@192.168.2.12 CSeq: 1 INVITE Contact: Supported: em,timer,replaces,path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-MP-108 FXO/v.4.60A.028 Content-Type: application/sdp Content-Length: 307 v=0 o=AudiocodesGW 1319836303 1319836234 IN IP4 192.168.2.12 s=Phone-Call c=IN IP4 192.168.2.12 t=0 0 m=audio 4000 RTP/AVP 2 18 0 101 a=rtpmap:2 g726-32/8000 a=rtpmap:18 g729/8000 a=fmtp:18 annexb=no a=rtpmap:0 pcmu/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv [Time: 15:2:23] 9d:15h:2m:23s ( sip_stack)(630 ) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 6 Dest: c0a8020a:5060 [Time: 15:2:23] 9d:15h:2m:23s ( sip_stack)(631 ) SIPTransaction::ResendLastMessage - Resending last message [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(632 ) ---- Incoming SIP Message from 192.168.2.10:5060 ---- [Time: 15:2:23] 9d:15h:2m:23s SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.2.12;branch=z9hG4bKac1319844491;received=192.168.2.12 From: "A B P INTL " ;tag=1c1319842710 To: ;tag=as5033aeb5 Call-ID: 131984243791200615223@192.168.2.12 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Proxy-Authenticate: Digest realm="asterisk", nonce="31f7d534" Content-Length: 0 [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(634 ) | |(SIPTU#0)407 State:Inviting(131984243791200615223@192.168.2.12) [Time: 15:2:23] 9d:15h:2m:23s ( sip_stack)(635 ) UdpRtxMngr::Remove 1 INVITE [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(636 ) ---- Outgoing SIP Message to 192.168.2.10:5060 ---- [Time: 15:2:23] 9d:15h:2m:23s ACK sip:200@192.168.2.10;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.2.12;branch=z9hG4bKac1319844491 Max-Forwards: 70 From: "A B P INTL " ;tag=1c1319842710 To: ;tag=as5033aeb5 Call-ID: 131984243791200615223@192.168.2.12 CSeq: 1 ACK Contact: Supported: em,timer,replaces,path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-MP-108 FXO/v.4.60A.028 Content-Length: 0 [Time: 15:2:23] 9d:15h:2m:23s ( lgr_flow)(638 ) ---- Outgoing SIP Message to 192.168.2.10:5060 ---- [Time: 15:2:23] 9d:15h:2m:23s ( sip_stack)(640 ) UdpRtxMngr::Transmit 2 INVITE Rtx Left: 6 Dest: c0a8020a:5060 [Time: 15:2:23] 9d:15h:2m:27s ( lgr_flow)(658 ) ---- Incoming SIP Message from 192.168.2.10:5060 ---- [Time: 15:2:27] 9d:15h:2m:27s SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.2.12;branch=z9hG4bKac1319874949;received=192.168.2.12 From: "A B P INTL " ;tag=1c1319842710 To: ;tag=as1ad667b2 Call-ID: 131984243791200615223@192.168.2.12 CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 263 v=0 o=root 19417 19417 IN IP4 192.168.2.10 s=session c=IN IP4 192.168.2.10 t=0 0 m=audio 10970 RTP/AVP 3 0 8 101 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(660 ) | |(SIPTU#0)200 OK State:Proceeding(131984243791200615223@192.168.2.12) [Time: 15:2:27] 9d:15h:2m:27s ( sip_stack)(661 ) SIPCall(#0) changes state from Proceeding to RemoteAccepted [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(662 ) | | | #0:SIP_CONNECT_EV(131984243791200615223@192.168.2.12) [Time: 15:2:27] 9d:15h:2m:27s ( lgr_stk_ses)(663 ) DtmfCapNegotiationAlgorithm :: TxDtmfMethod = DTMF_RFC2833_SUPPORTED [Time: 15:2:27] 9d:15h:2m:27s ( lgr_stk_ses)(664 ) DtmfCapNegotiationAlgorithm :: TxRtpRfc2833Payload = 101 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_stk_ses)(665 ) SendToCall - event: DTMF_CONTROL_EV m_Call = 16060224 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(666 ) | | #0:DTMF_CONTROL_EV:(131984243791200615223@192.168.2.12) [Time: 15:2:27] 9d:15h:2m:27s ( lgr_stk_ses)(667 ) SendToCall - event: OPEN_LOGICAL_CHANNEL_ACK m_Call = 16060224 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(668 ) | | #0:OPEN_LOGICAL_CHANNEL_ACK:(131984243791200615223@192.168.2.12) [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(669 ) | #0:OPEN_LOGICAL_CHANNEL_ACK : (131984243791200615223@192.168.2.12) [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(670 ) | #0:OPEN_VOICE-NOT_READY (MS:1, HS:0, Ready:0) [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(671 ) | |(SIPTU#0)MEDIA_RESPONSE_REQ State:RemoteAccepted(131984243791200615223@192.168.2.12) [Time: 15:2:27] 9d:15h:2m:27s ( sip_stack)(672 ) SIPCall(#0) changes state from RemoteAccepted to Connected [Time: 15:2:27] 9d:15h:2m:27s ( sip_stack)(673 ) UdpRtxMngr::Remove 2 INVITE [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(674 ) ---- Outgoing SIP Message to 192.168.2.10:5060 ---- [Time: 15:2:27] 9d:15h:2m:27s ACK sip:200@192.168.2.10 SIP/2.0 Via: SIP/2.0/UDP 192.168.2.12;branch=z9hG4bKac1323991828 Max-Forwards: 70 From: "A B P INTL " ;tag=1c1319842710 To: ;tag=as1ad667b2 Call-ID: 131984243791200615223@192.168.2.12 CSeq: 2 ACK Contact: Supported: em,timer,replaces,path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-MP-108 FXO/v.4.60A.028 Content-Length: 0 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_stk_ses)(676 ) SendToCall - event: CONNECT m_Call = 16060224 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(677 ) | | #0:CONNECT:(131984243791200615223@192.168.2.12) [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(678 ) | #0:CONNECT : (131984243791200615223@192.168.2.12) [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdif)(679 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(680 ) | #0:OPEN_VOICE (IP:c0a8020a, RTP:10970, RTCP:10971, Coder:g711Ulaw64k20_,Dtmf:gwRFC2833RalayDTMF,Rx payload:101,Tx payload:101 ,RTPmode:1) [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdex)(681 ) IPDIFFSERV value is 0 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdif)(682 ) #0:ActivateChannel: Socks=13 CID=0 Trunk:-1 BChannel:0 RemoteIP=c0a8020a RemotePort=10970 FaxModemDet=NO_FAX_MODEM_DETECTED [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdif)(683 ) #0:OpenChannel:on Trunk -1 BChannel:0 CID=0 with Coder: g711Ulaw64k20 DetectorSide: TEL FaxModemDet NO_FAX_MODEM_DETECTED [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdif)(684 ) #0:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdif)(685 ) OpenChannel, CoderType = 1, Interval = 3, M = 1 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdif)(686 ) #0:ConfigureFaxModemChannelParams VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdif)(687 ) #0:ConfigureFaxModemChannelParams ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=70, DJBufOptFactor=7) [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdif)(688 ) Setting ActivateRTP_RTCPCmd.Cmd.IPPrecedence to 0 and ActivateRTP_RTCPCmd.Cmd.IPTOS to 0 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdif)(689 ) #0:Configure Detectors (Detection=0) [Time: 15:2:27] 9d:15h:2m:27s ( lgr_psbrdif)(690 ) Turn ringer ON for channel 0 [Time: 15:2:27] 9d:15h:2m:27s ( lgr_flow)(691 ) | #0:FXO Seize Line [Time: 15:2:27] 9d:15h:2m:29s SWWD : Run Task GWAP Ticks 2 [Code:20019] [CID:0] [Time: 15:2:29] 9d:15h:2m:29s IP 5125c8, LR 513068, Trace : 5477b8 513068 ae608 2d2e94 a2e28 [Code:20019] [CID:0] [Time: 15:2:29] 9d:15h:2m:29s SWWD : Wait Task GWAP [Code:20019] [CID:0] [Time: 15:2:29] 9d:15h:2m:29s st 0, cpr 14, ewc 0, cm 2001, ew 0, TBack = 5477b8 7bc630 2d3128 2d3708 2cd3c8 [Code:20019] [CID:0] [Time: 15:2:29] 9d:15h:2m:43s ( lgr_psbrdex)(692 ) recv <-- DIGIT(2) Ch:0 OnTime:0 InterTime:142050 Direction:0 System:1 [Time: 15:2:43] 9d:15h:2m:43s ( lgr_flow)(693 ) #0:DIGIT_EV [Time: 15:2:43] 9d:15h:2m:43s ( lgr_flow)(694 ) | #0:DIGIT_EV [Time: 15:2:43] 9d:15h:2m:43s ( lgr_flow)(695 ) | #0:DTMF (send) : (131984243791200615223@192.168.2.12) [Time: 15:2:43] 9d:15h:2m:43s ( lgr_flow)(696 ) | | #0:DTMF:(131984243791200615223@192.168.2.12) [Time: 15:2:43] 9d:15h:2m:43s ( lgr_flow)(697 ) | | | #0:DTMF(131984243791200615223@192.168.2.12) [Time: 15:2:43] 9d:15h:2m:43s ( lgr_psbrdex)(698 ) recv <-- DIGIT(2) Ch:0 OnTime:355 InterTime:142050 Direction:0 System:1 [Time: 15:2:43] 9d:15h:2m:43s ( lgr_flow)(699 ) #0:DIGIT_EV [Time: 15:2:43] 9d:15h:2m:43s ( lgr_flow)(700 ) | #0:DIGIT_EV [Time: 15:2:43] 9d:15h:2m:43s ( lgr_flow)(701 ) | #0:DTMF (send) : (131984243791200615223@192.168.2.12) [Time: 15:2:43] 9d:15h:2m:43s ( lgr_flow)(702 ) | | #0:DTMF:(131984243791200615223@192.168.2.12) [Time: 15:2:43] 9d:15h:2m:43s ( lgr_flow)(703 ) | | | #0:DTMF(131984243791200615223@192.168.2.12) [Time: 15:2:43] 9d:15h:2m:51s [Src=192.168.2.10/10970 Dst=4000 PType=6] ErrMgs=31 Cid=0: RTP packet duplicated: CID=0 SeqNum=46578 RTPPT=101 [Code:3700e] [Time: 15:2:51] 9d:15h:2m:59s ( lgr_flow)(704 ) ---- Incoming SIP Message from 192.168.2.10:5060 ---- [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(705 ) BYE sip:9724090813@192.168.2.12 SIP/2.0 Via: SIP/2.0/UDP 192.168.2.10:5060;branch=z9hG4bK792d9b08;rport From: ;tag=as1ad667b2 To: "A B P INTL " ;tag=1c1319842710 Contact: Call-ID: 131984243791200615223@192.168.2.12 CSeq: 102 BYE User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(706 ) | |(SIPTU#0)BYE State:Connected(131984243791200615223@192.168.2.12) [Time: 15:2:59] 9d:15h:2m:59s ( sip_stack)(707 ) SIPCall(#0) changes state from Connected to Disconnected [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(708 ) | | | #0:SIP_DISCONNECT_EV(131984243791200615223@192.168.2.12) [Time: 15:2:59] 9d:15h:2m:59s ( lgr_stk_ses)(709 ) SendToCall - event: RELEASE m_Call = 16060224 [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(710 ) | | #0:RELEASE:(131984243791200615223@192.168.2.12) [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(711 ) | | #0:RELEASE_ACK:(131984243791200615223@192.168.2.12) [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(712 ) | #0:RELEASE GWAPP_NORMAL_CALL_CLEAR : (131984243791200615223@192.168.2.12) [Time: 15:2:59] 9d:15h:2m:59s ( lgr_psbrdif)(713 ) #0:StopRTP_RTCP on channel 0 [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(714 ) | #0:RELEASE_ACK (send) : (131984243791200615223@192.168.2.12) [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(715 ) | | #0:RELEASE_ACK:(131984243791200615223@192.168.2.12) [Time: 15:2:59] 9d:15h:2m:59s ( lgr_psbrdex)(716 ) InsertBoardEvent- event 103 inserted channel 0 [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(717 ) | | | #0:RELEASE_ACK(131984243791200615223@192.168.2.12) [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(718 ) | |(SIPTU#0)DISCONNECT_RESPONSE State:Disconnected(131984243791200615223@192.168.2.12) [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(719 ) ---- Outgoing SIP Message to 192.168.2.10:5060 ---- [Time: 15:2:59] 9d:15h:2m:59s SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.2.10:5060;branch=z9hG4bK792d9b08;rport From: ;tag=as1ad667b2 To: "A B P INTL " ;tag=1c1319842710 Call-ID: 131984243791200615223@192.168.2.12 CSeq: 102 BYE Contact: Supported: em,timer,replaces,path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-MP-108 FXO/v.4.60A.028 Content-Length: 0 [Time: 15:2:59] 9d:15h:2m:59s ( sip_stack)(721 ) AcSIPStackAPI::FreeCallAPI - #0 [Time: 15:2:59] 9d:15h:2m:59s ( sip_stack)(722 ) Setting ApplicationCall of AcSIPCall 15543872 to NULL [Time: 15:2:59] 9d:15h:2m:59s ( lgr_stk_mngr)(723 ) Resource StackSession <#0> Deleted [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(724 ) #0:RELEASE_BECAUSE_IP_TIMER_EXPIRED_EV [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(725 ) | #0:RELEASE_BECAUSE_IP_TIMER_EXPIRED_EV [Time: 15:2:59] 9d:15h:2m:59s ( lgr_psbrdif)(726 ) Turn ringer OFF for channel 0 [Time: 15:2:59] 9d:15h:2m:59s ( lgr_flow)(727 ) | #0:FXO Release Line [Time: 15:2:59] 9d:15h:2m:59s ( lgr_psbrdif)(728 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:2:59] 9d:15h:3m:4s ( lgr_flow)(729 ) | | TransactionUserMngr::ReturnSIPCall - #0 [Time: 15:3:4] 9d:15h:3m:4s ( sip_stack)(730 ) SIPCall(#0) changes state from Disconnected to Idle [Time: 15:3:4] 9d:15h:3m:5s ( lgr_psbrdex)(731 ) recv <-- DIGIT(9) Ch:0 OnTime:0 InterTime:22450 Direction:0 System:1 [Time: 15:3:5] 9d:15h:3m:5s ( lgr_flow)(732 ) #0:DIGIT_EV [Time: 15:3:5] 9d:15h:3m:5s ( lgr_flow)(733 ) | #0:DIGIT_EV [Time: 15:3:5] 9d:15h:3m:5s ( lgr_psbrdif)(734 ) Turn ringer OFF for channel 0 [Time: 15:3:5] 9d:15h:3m:5s ( lgr_flow)(735 ) | #0:FXO Release Line [Time: 15:3:5] 9d:15h:3m:5s ( lgr_psbrdif)(736 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:5] 9d:15h:3m:5s ( lgr_psbrdex)(737 ) recv <-- DIGIT(9) Ch:0 OnTime:70 InterTime:22450 Direction:0 System:1 [Time: 15:3:5] 9d:15h:3m:6s ( lgr_flow)(738 ) #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(739 ) | #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(740 ) Turn ringer OFF for channel 0 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(741 ) | #0:FXO Release Line [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(742 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdex)(743 ) recv <-- DIGIT(7) Ch:0 OnTime:0 InterTime:115 Direction:0 System:1 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(744 ) #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(745 ) | #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(746 ) Turn ringer OFF for channel 0 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(747 ) | #0:FXO Release Line [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(748 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdex)(749 ) recv <-- DIGIT(7) Ch:0 OnTime:85 InterTime:115 Direction:0 System:1 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(750 ) #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(751 ) | #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(752 ) Turn ringer OFF for channel 0 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(753 ) | #0:FXO Release Line [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(754 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdex)(755 ) recv <-- DIGIT(2) Ch:0 OnTime:0 InterTime:100 Direction:0 System:1 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(756 ) #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(757 ) | #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(758 ) Turn ringer OFF for channel 0 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(759 ) | #0:FXO Release Line [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(760 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdex)(761 ) recv <-- DIGIT(2) Ch:0 OnTime:100 InterTime:100 Direction:0 System:1 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(762 ) #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(763 ) | #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(764 ) Turn ringer OFF for channel 0 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(765 ) | #0:FXO Release Line [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(766 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdex)(767 ) recv <-- DIGIT(7) Ch:0 OnTime:0 InterTime:100 Direction:0 System:1 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(768 ) #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(769 ) | #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(770 ) Turn ringer OFF for channel 0 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(771 ) | #0:FXO Release Line [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(772 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdex)(773 ) recv <-- DIGIT(7) Ch:0 OnTime:100 InterTime:100 Direction:0 System:1 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(774 ) #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(775 ) | #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(776 ) Turn ringer OFF for channel 0 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(777 ) | #0:FXO Release Line [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(778 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdex)(779 ) recv <-- DIGIT(4) Ch:0 OnTime:0 InterTime:105 Direction:0 System:1 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(780 ) #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(781 ) | #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(782 ) Turn ringer OFF for channel 0 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(783 ) | #0:FXO Release Line [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(784 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdex)(785 ) recv <-- DIGIT(4) Ch:0 OnTime:95 InterTime:105 Direction:0 System:1 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(786 ) #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(787 ) | #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(788 ) Turn ringer OFF for channel 0 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(789 ) | #0:FXO Release Line [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(790 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdex)(791 ) recv <-- DIGIT(5) Ch:0 OnTime:0 InterTime:100 Direction:0 System:1 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(792 ) #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(793 ) | #0:DIGIT_EV [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(794 ) Turn ringer OFF for channel 0 [Time: 15:3:6] 9d:15h:3m:6s ( lgr_flow)(795 ) | #0:FXO Release Line [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdif)(796 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:6] 9d:15h:3m:6s ( lgr_psbrdex)(797 ) recv <-- DIGIT(5) Ch:0 OnTime:100 InterTime:100 Direction:0 System:1 [Time: 15:3:6] 9d:15h:3m:7s ( lgr_flow)(798 ) #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(799 ) | #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(800 ) Turn ringer OFF for channel 0 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(801 ) | #0:FXO Release Line [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(802 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdex)(803 ) recv <-- DIGIT(1) Ch:0 OnTime:0 InterTime:105 Direction:0 System:1 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(804 ) #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(805 ) | #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(806 ) Turn ringer OFF for channel 0 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(807 ) | #0:FXO Release Line [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(808 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdex)(809 ) recv <-- DIGIT(1) Ch:0 OnTime:95 InterTime:105 Direction:0 System:1 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(810 ) #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(811 ) | #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(812 ) Turn ringer OFF for channel 0 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(813 ) | #0:FXO Release Line [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(814 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdex)(815 ) recv <-- DIGIT(8) Ch:0 OnTime:0 InterTime:105 Direction:0 System:1 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(816 ) #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(817 ) | #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(818 ) Turn ringer OFF for channel 0 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(819 ) | #0:FXO Release Line [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(820 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdex)(821 ) recv <-- DIGIT(8) Ch:0 OnTime:95 InterTime:105 Direction:0 System:1 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(822 ) #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(823 ) | #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(824 ) Turn ringer OFF for channel 0 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(825 ) | #0:FXO Release Line [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(826 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdex)(827 ) recv <-- DIGIT(4) Ch:0 OnTime:0 InterTime:100 Direction:0 System:1 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(828 ) #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(829 ) | #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(830 ) Turn ringer OFF for channel 0 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(831 ) | #0:FXO Release Line [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(832 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdex)(833 ) recv <-- DIGIT(4) Ch:0 OnTime:100 InterTime:100 Direction:0 System:1 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(834 ) #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(835 ) | #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(836 ) Turn ringer OFF for channel 0 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(837 ) | #0:FXO Release Line [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(838 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdex)(839 ) recv <-- DIGIT(9) Ch:0 OnTime:0 InterTime:105 Direction:0 System:1 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(840 ) #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(841 ) | #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(842 ) Turn ringer OFF for channel 0 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(843 ) | #0:FXO Release Line [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(844 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdex)(845 ) recv <-- DIGIT(9) Ch:0 OnTime:95 InterTime:105 Direction:0 System:1 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(846 ) #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(847 ) | #0:DIGIT_EV [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(848 ) Turn ringer OFF for channel 0 [Time: 15:3:7] 9d:15h:3m:7s ( lgr_flow)(849 ) | #0:FXO Release Line [Time: 15:3:7] 9d:15h:3m:7s ( lgr_psbrdif)(850 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 15:3:7]