rbjohnson78
Technical User
I've been able to setup the SIP line correctly I think, but I'm not entirely sure how to read the Monitor output. I'm not able to make or receive calls. Below is the output I have. Thanks for any help.
268256mS SIP Reg/Opt Rx: 18
SIP/2.0 200 OK
Via: SIP/2.0/UDP 173.160.120.20:5060;rport=5060;branch=z9hG4bK1c28691b4f6f3e51a06478cac33d3d18
From: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=13911d4e634dcdc0
To: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.0517
Call-ID: be628e3f5dfc69726131ba39d6b2ae15@173.160.120.20
CSeq: 1282515230 OPTIONS
Server: Bandwidth.com TRM (bw7.gold.13)
Content-Length: 0
272863mS CMCallEvt: 0.1002.0 -1 BaseEP: NEW CMEndpoint f529af10 TOTAL NOW=1 CALL_LIST=0
272863mS CMCallEvt: 0.1002.0 -1 Rob Johnson.-1: NEW CMExtnEndpoint f529af10, Name=Rob Johnson, Extn=213, Phys Extn=860
272864mS CMCallEvt: CREATE CALL:1 (f529a3e0)
272864mS CMCallEvt: 0.1003.0 -1 BaseEP: NEW CMEndpoint f5298f9c TOTAL NOW=2 CALL_LIST=0
272866mS CMExtnEvt: Rob Johnson: CMExtnHandler::SetCurrent( id: 0->1002 )
272867mS CMExtnRx: v=213, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[213] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Rob Johnson
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Rob Johnson
IE CMIEFastStartInfoData (6)
272867mS CMCallEvt: 254.1002.0 1 Rob Johnson.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
272867mS CMExtnEvt: v=1 State, new=Dialling old=Idle,0,0,Rob Johnson
272868mS CMTARGET: 254.1002.0 1 Rob Johnson.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=213 dir=out complete=0 ses=0
272868mS CMTARGET: 254.1002.0 1 Rob Johnson.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
272868mS CMCallEvt: 254.1002.0 1 Rob Johnson.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
272871mS CMExtnTx: v=213, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
IE CMIEFastStartInfoData (6)
Timed: 01/11/11 11:03
272871mS CMExtnTx: v=860, p1=8003
CMFacility
Line: type=IPLine 250 Call: lid=254 id=1 in=1
IE CMIEFastStartInfoData (6)
272872mS CMExtnTx: v=213, p1=0
CMSetupAck
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Timed: 01/11/11 11:03
272874mS CD: CALL: 254.1002.0 BState=Idle Cut=1 Music=3.0 Aend="Rob Johnson(213)" (0.0) Bend="" [] (0.0) CalledNum= () CallingNum=213 (Rob Johnson) Internal=1 Time=9 AState=DialInitiated
272875mS CMMap: PCG::AddToneGenerator g D1[1] for cp[96]b0r1 append pcp[97]b0r1 (total 1)
272875mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[97]b0r1 Configure 0.0
272875mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[97]b0r1 ConnectIndication 0.0
272875mS CMMap: a=0.0 b=0.0 pcp[96]b0r1 RTPD1
272986mS RES: Tue 1/11/2011 11:03:01 FreeMem=62376628(2) CMMsg=5 (6) Buff=5200 960 999 7454 5 Links=6546
272987mS RES2: IP 500 8.0(3) Tasks=38 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=56 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 CF=UMNT
274041mS H323Evt: RTP(50r): 192.168.100.240/1024 192.168.100.242/2166 CODEC=G729A8K(6) PKTSZ=20 RFC2833=off AGE=1167 SENT=58 (avg size=20) RECV=50 (avg size=20)
274441mS PRN: 213: Digit Pressed 9
274441mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
274441mS CMTARGET: 254.1002.0 1 Rob Johnson.0: Setting Hard Timer 4000
274442mS CMTARGET: 254.1002.0 1 Rob Johnson.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=213 dir=out complete=0 ses=0
274442mS CMTARGET: 254.1002.0 1 Rob Johnson.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
274442mS CMTARGET: 254.1002.0 1 Rob Johnson.0: SYS SC: 9 2 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
274443mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
274443mS CMARS: MakeCallTarget - Called Number:
274443mS CMCallEvt: 0.1004.0 -1 BaseEP: NEW CMEndpoint f528e788 TOTAL NOW=3 CALL_LIST=1
274444mS CMTARGET: 254.1002.0 1 Rob Johnson.0: CancelTimer CMTCDelayedProcessing
274444mS CMTARGET: 254.1002.0 1 Rob Johnson.0: INITIAL TARGETING SUCCEEDED
274444mS CMTARGET: 254.1002.0 1 Rob Johnson.0: GetNoAnswerTimer:15
274445mS CMCallEvt: 0.1003.0 1 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
274446mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
274447mS CMCallEvt: 0.1003.0 1 TargetingEP: RequestEnd 0.1004.0 1 ARS for Main
274447mS CMTARGET: 254.1002.0 1 Rob Johnson.0: CancelTimer CMTCNoAnswerTimeout
274448mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
274449mS CMExtnTx: v=213, p1=0
CMProgress
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [TRANSFERABLE_SEC_DIAL_TONE]
Timed: 01/11/11 11:03
274449mS CMARS: FORM: Main - Received Number:
274449mS CMARS: CMARSTargetingPending
274449mS CMCallEvt: 0.1003.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
274450mS CMCallEvt: 0.1003.0 -1 BaseEP: DELETE CMEndpoint f5298f9c TOTAL NOW=2 CALL_LIST=1
274450mS CD: CALL: 254.1002.0 BState=Idle Cut=2 Music=3.0 Aend="Rob Johnson(213)" (0.0) Bend="" [] (0.0) CalledNum=9 () CallingNum=213 (Rob Johnson) Internal=1 Time=1586 AState=Dialling
274451mS CMMap: a=0.0 b=0.0 pcp[96]b0r1 RTPD1
275250mS PRN: 213: Digit Pressed 6
275250mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[6] Type=Default (100) Reason=CMDRdirect Keypad[6]
275251mS CMARS: FORM: Main - Received Number: 6
275251mS CMARS: CMARSTargetingPending
275252mS CMMap: a=0.0 b=0.0 pcp[96]b0r1 RTPD0
275499mS PRN: 213: Digit Pressed 5
275500mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect Keypad[5]
275500mS CMARS: FORM: Main - Received Number: 65
275500mS CMARS: CMARSTargetingPending
275772mS PRN: 213: Digit Pressed 1
275773mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
275773mS CMARS: FORM: Main - Received Number: 651
275774mS CMARS: CMARSTargetingPending
276033mS PRN: 213: Digit Pressed 2
276034mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[2] Type=Default (100) Reason=CMDRdirect Keypad[2]
276034mS CMARS: FORM: Main - Received Number: 6512
276034mS CMARS: CMARSTargetingPending
276278mS PRN: 213: Digit Pressed 3
276279mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[3] Type=Default (100) Reason=CMDRdirect Keypad[3]
276279mS CMARS: FORM: Main - Received Number: 65123
276280mS CMARS: CMARSTargetingPending
276559mS PRN: 213: Digit Pressed 8
276559mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[8] Type=Default (100) Reason=CMDRdirect Keypad[8]
276560mS CMARS: FORM: Main - Received Number: 651238
276560mS CMARS: CMARSTargetingPending
276830mS PRN: 213: Digit Pressed 5
276830mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect Keypad[5]
276831mS CMARS: FORM: Main - Received Number: 6512385
276831mS CMARS: CMARSTargetingPending
277199mS PRN: 213: Digit Pressed 6
277199mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[6] Type=Default (100) Reason=CMDRdirect Keypad[6]
277200mS CMARS: FORM: Main - Received Number: 65123856
277200mS CMARS: CMARSTargetingPending
277485mS PRN: 213: Digit Pressed 6
277486mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[6] Type=Default (100) Reason=CMDRdirect Keypad[6]
277486mS CMARS: FORM: Main - Received Number: 651238566
277486mS CMARS: CMARSTargetingPending
277772mS PRN: 213: Digit Pressed 3
277773mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[3] Type=Default (100) Reason=CMDRdirect Keypad[3]
277773mS CMARS: FORM: Main - Received Number: 6512385663
277774mS CMARS: CMARSTargetingPending
278925mS H323Evt: Recv: RegistrationRequest 192.168.100.242; Endpoints registered: 1; Endpoints in registration: 0
280486mS RES: Tue 1/11/2011 11:03:08 FreeMem=62386812(2) CMMsg=5 (6) Buff=5200 960 1000 7454 5 Links=6555
280487mS RES2: IP 500 8.0(3) Tasks=38 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=51 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 CF=UMNT
282443mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 6512385663 Addr: f528e788
282443mS CMARS: FORM: Main - Received Number: 6512385663
282443mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: N - Called_Party: 6512385663 - Line Group Id: 18
282443mS CMARS: FindActiveARSByGroupID GroupID=18 - Not Found
282444mS CMLRQ: FindActiveLRQByGroupID GroupID=18 - Not Found
282444mS Sip: License, Valid 1, Available 5, Consumed 0
282444mS CMCallEvt: 0.1005.0 -1 BaseEP: NEW CMEndpoint f528cc14 TOTAL NOW=3 CALL_LIST=1
282445mS Stun: Info: media ports will be mapped with STUN.
282448mS CMARS: FOUND LINE - Line Id: 18 - using line group id: 18 - Called Number: 6512385663 - Calling Number: 213
282449mS CMARS: SEND Setup TO LINE
282449mS CMCallEvt: 18.1005.0 1 SIPTrunk Endpoint: StateChange: END=child CMCSIdle->CMCSOffering
282450mS CMLineTx: v=0
CMSetup
Line: type=SIPLine 18 Call: lid=18 id=1005 in=0
Called[6512385663] Type=Default (100) Reason=CMDRdirect SndComp Calling[213] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Rob Johnson
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Rob Johnson
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Rob Johnson
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Rob Johnson
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=213
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=8 class=CMDeviceH323Phone type=110 number=8003 channel=0 rx_gain=32 tx_gain=32 ep_callid=1002 ipaddr=173.160.120.20 apps=0
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIEFastStartInfoData (6)
Locale: enu
282450mS Stun: Creating STUNClient to resolve binding for 173.160.120.20:49152, server 69.90.186.13:3478
282450mS Stun: Creating STUNUdpClient port 49152 address f5293068
282451mS Stun: StunClient: ResolveIPAndPort 173.160.120.20:49152
282451mS Stun: Creating STUNClient to resolve binding for 173.160.120.20:49153, server 69.90.186.13:3478
282451mS Stun: Creating STUNUdpClient port 49153 address f52921ac
282451mS Stun: StunClient: ResolveIPAndPort 173.160.120.20:49153
282452mS CD: CALL: 254.1002.0 BState=Idle Cut=2 Music=0.0 Aend="Rob Johnson(213)" (0.0) Bend="" [Line 18] (0.0) CalledNum=96512385663 () CallingNum=213 (Rob Johnson) Internal=1 Time=9588 AState=Dialling
283451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 0
283451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 0
284451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 1
284451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 1
286451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 2
286451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 2
290451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 3
290451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 3
298215mS SIP Reg/Opt Tx: 18
OPTIONS sip:Unknown@sp-udp01.iad.bandwidth.com SIP/2.0
Via: SIP/2.0/UDP 173.160.120.20:5060;rport;branch=z9hG4bK2718b5da0fcd047812e0ec02ec58181f
From: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=af115bfa8c5ad01b
To: <sip:Unknown@sp-udp01.iad.bandwidth.com>
Call-ID: b9368fe5be32b31781a629c12523d64c@173.160.120.20
CSeq: 1140286175 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0
298215mS SIP Tx: UDP 173.160.120.20:5060 -> 216.82.224.202:5060
OPTIONS sip:Unknown@sp-udp01.iad.bandwidth.com SIP/2.0
Via: SIP/2.0/UDP 173.160.120.20:5060;rport;branch=z9hG4bK2718b5da0fcd047812e0ec02ec58181f
From: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=af115bfa8c5ad01b
To: <sip:Unknown@sp-udp01.iad.bandwidth.com>
Call-ID: b9368fe5be32b31781a629c12523d64c@173.160.120.20
CSeq: 1140286175 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0
298253mS SIP Rx: UDP 216.82.224.202:5060 -> 173.160.120.20:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 173.160.120.20:5060;rport=5060;branch=z9hG4bK2718b5da0fcd047812e0ec02ec58181f
From: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=af115bfa8c5ad01b
To: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.238a
Call-ID: b9368fe5be32b31781a629c12523d64c@173.160.120.20
CSeq: 1140286175 OPTIONS
Server: Bandwidth.com TRM (bw7.gold.13)
Content-Length: 0
298255mS SIP Reg/Opt Rx: 18
SIP/2.0 200 OK
Via: SIP/2.0/UDP 173.160.120.20:5060;rport=5060;branch=z9hG4bK2718b5da0fcd047812e0ec02ec58181f
From: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=af115bfa8c5ad01b
To: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.238a
Call-ID: b9368fe5be32b31781a629c12523d64c@173.160.120.20
CSeq: 1140286175 OPTIONS
Server: Bandwidth.com TRM (bw7.gold.13)
Content-Length: 0
298451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 4
298451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 4
298486mS RES: Tue 1/11/2011 11:03:26 FreeMem=62348760(2) CMMsg=5 (6) Buff=5200 960 1000 7453 5 Links=6284
298487mS RES2: IP 500 8.0(3) Tasks=38 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=53 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 CF=UMNT
303486mS RES: Tue 1/11/2011 11:03:31 FreeMem=62361776(2) CMMsg=5 (6) Buff=5200 960 1000 7453 5 Links=6288
303487mS RES2: IP 500 8.0(3) Tasks=38 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=52 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 CF=UMNT
307523mS CMExtnTx: v=860, p1=8003
CMFacility
Line: type=IPLine 250 Call: lid=254 id=1 in=1
IE CMIEFastStartInfoData (6)
307523mS CMExtnRx: v=213, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
307524mS CMCallEvt: 254.1002.0 1 Rob Johnson.0: StateChange: END=A CMCSDialling->CMCSCompleted
307524mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Dialling,0,0,Rob Johnson
307525mS CMExtnTx: v=213, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
IE CMIEFastStartInfoData (6)
Timed: 01/11/11 11:03
307527mS CMLOGGING: CALL:2011/11/0111:03,00:00:00,000,213,O,96512385663,96512385663,RobJohnson,,,1,,""n/a,0
307527mS CD: CALL: 254.1002.0 BState=Idle Cut=2 Music=0.0 Aend="Rob Johnson(213)" (0.0) Bend="" [Line 18] (0.0) CalledNum=96512385663 () CallingNum=213 (Rob Johnson) Internal=1 Time=34663 AState=Idle
307527mS CD: CALL: 254.1002.0 Deleted
307527mS CMExtnEvt: Rob Johnson: CALL LOST (CMCauseNormal)
307527mS CMExtnEvt: Rob Johnson: Extn(213) Calling Party Number(213) Type(CMNTypeInternal)
307528mS CMExtnEvt: Rob Johnson: CMExtnHandler::SetCurrent( id: 1002->0 )
307529mS CMCallEvt: 254.1002.0 -1 Rob Johnson.-1: StateChange: END=X CMCSCompleted->CMCSDelete
307529mS CMARS: CMARSEndpoint::CallLost(cause=16) - Address: 0.1004.0 1 ARS for Main - Call State: CMCSOverlapRecv
307530mS CMCallEvt: 18.1005.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSOffering->CMCSCompleted
307531mS CMCallEvt: 18.1005.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
307531mS Stun: ~STUNClient 173.160.120.20:49152
307531mS Stun: ~STUNClient 173.160.120.20:49153
307532mS CMCallEvt: 18.1005.0 -1 BaseEP: DELETE CMEndpoint f528cc14 TOTAL NOW=2 CALL_LIST=0
307532mS CMCallEvt: 0.1004.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
307533mS CMCallEvt: 0.1004.0 -1 BaseEP: DELETE CMEndpoint f528e788 TOTAL NOW=1 CALL_LIST=0
307533mS CMCallEvt: END CALL:1 (f529a3e0)
307534mS CMTARGET: 254.1002.0 -1 BaseEP: ~CMTargetHandler f5293150 ep f529af10
307534mS CMCallEvt: 254.1002.0 -1 BaseEP: DELETE CMEndpoint f529af10 TOTAL NOW=0 CALL_LIST=0
307536mS H323Evt: RTP(END): 192.168.100.240/1024 192.168.100.242/2166 CODEC=G729A8K(6) PKTSZ=20 RFC2833=off AGE=34662 SENT=119 RECV=110 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
309529mS CMExtnEvt: Rob Johnson: Recover Timer reason=CMTRWrapUp
309529mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Rob Johnson
309529mS CMExtnTx: v=213, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[Rob Johnson Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Rob Johnson Msgs=0]
Timed: 01/11/11 11:03
********** Warning: Logging to Screen Stopped **********
268256mS SIP Reg/Opt Rx: 18
SIP/2.0 200 OK
Via: SIP/2.0/UDP 173.160.120.20:5060;rport=5060;branch=z9hG4bK1c28691b4f6f3e51a06478cac33d3d18
From: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=13911d4e634dcdc0
To: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.0517
Call-ID: be628e3f5dfc69726131ba39d6b2ae15@173.160.120.20
CSeq: 1282515230 OPTIONS
Server: Bandwidth.com TRM (bw7.gold.13)
Content-Length: 0
272863mS CMCallEvt: 0.1002.0 -1 BaseEP: NEW CMEndpoint f529af10 TOTAL NOW=1 CALL_LIST=0
272863mS CMCallEvt: 0.1002.0 -1 Rob Johnson.-1: NEW CMExtnEndpoint f529af10, Name=Rob Johnson, Extn=213, Phys Extn=860
272864mS CMCallEvt: CREATE CALL:1 (f529a3e0)
272864mS CMCallEvt: 0.1003.0 -1 BaseEP: NEW CMEndpoint f5298f9c TOTAL NOW=2 CALL_LIST=0
272866mS CMExtnEvt: Rob Johnson: CMExtnHandler::SetCurrent( id: 0->1002 )
272867mS CMExtnRx: v=213, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[213] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Rob Johnson
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Rob Johnson
IE CMIEFastStartInfoData (6)
272867mS CMCallEvt: 254.1002.0 1 Rob Johnson.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
272867mS CMExtnEvt: v=1 State, new=Dialling old=Idle,0,0,Rob Johnson
272868mS CMTARGET: 254.1002.0 1 Rob Johnson.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=213 dir=out complete=0 ses=0
272868mS CMTARGET: 254.1002.0 1 Rob Johnson.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
272868mS CMCallEvt: 254.1002.0 1 Rob Johnson.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
272871mS CMExtnTx: v=213, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
IE CMIEFastStartInfoData (6)
Timed: 01/11/11 11:03
272871mS CMExtnTx: v=860, p1=8003
CMFacility
Line: type=IPLine 250 Call: lid=254 id=1 in=1
IE CMIEFastStartInfoData (6)
272872mS CMExtnTx: v=213, p1=0
CMSetupAck
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Timed: 01/11/11 11:03
272874mS CD: CALL: 254.1002.0 BState=Idle Cut=1 Music=3.0 Aend="Rob Johnson(213)" (0.0) Bend="" [] (0.0) CalledNum= () CallingNum=213 (Rob Johnson) Internal=1 Time=9 AState=DialInitiated
272875mS CMMap: PCG::AddToneGenerator g D1[1] for cp[96]b0r1 append pcp[97]b0r1 (total 1)
272875mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[97]b0r1 Configure 0.0
272875mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[97]b0r1 ConnectIndication 0.0
272875mS CMMap: a=0.0 b=0.0 pcp[96]b0r1 RTPD1
272986mS RES: Tue 1/11/2011 11:03:01 FreeMem=62376628(2) CMMsg=5 (6) Buff=5200 960 999 7454 5 Links=6546
272987mS RES2: IP 500 8.0(3) Tasks=38 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=56 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 CF=UMNT
274041mS H323Evt: RTP(50r): 192.168.100.240/1024 192.168.100.242/2166 CODEC=G729A8K(6) PKTSZ=20 RFC2833=off AGE=1167 SENT=58 (avg size=20) RECV=50 (avg size=20)
274441mS PRN: 213: Digit Pressed 9
274441mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
274441mS CMTARGET: 254.1002.0 1 Rob Johnson.0: Setting Hard Timer 4000
274442mS CMTARGET: 254.1002.0 1 Rob Johnson.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=213 dir=out complete=0 ses=0
274442mS CMTARGET: 254.1002.0 1 Rob Johnson.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
274442mS CMTARGET: 254.1002.0 1 Rob Johnson.0: SYS SC: 9 2 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
274443mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
274443mS CMARS: MakeCallTarget - Called Number:
274443mS CMCallEvt: 0.1004.0 -1 BaseEP: NEW CMEndpoint f528e788 TOTAL NOW=3 CALL_LIST=1
274444mS CMTARGET: 254.1002.0 1 Rob Johnson.0: CancelTimer CMTCDelayedProcessing
274444mS CMTARGET: 254.1002.0 1 Rob Johnson.0: INITIAL TARGETING SUCCEEDED
274444mS CMTARGET: 254.1002.0 1 Rob Johnson.0: GetNoAnswerTimer:15
274445mS CMCallEvt: 0.1003.0 1 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
274446mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
274447mS CMCallEvt: 0.1003.0 1 TargetingEP: RequestEnd 0.1004.0 1 ARS for Main
274447mS CMTARGET: 254.1002.0 1 Rob Johnson.0: CancelTimer CMTCNoAnswerTimeout
274448mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
274449mS CMExtnTx: v=213, p1=0
CMProgress
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [TRANSFERABLE_SEC_DIAL_TONE]
Timed: 01/11/11 11:03
274449mS CMARS: FORM: Main - Received Number:
274449mS CMARS: CMARSTargetingPending
274449mS CMCallEvt: 0.1003.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
274450mS CMCallEvt: 0.1003.0 -1 BaseEP: DELETE CMEndpoint f5298f9c TOTAL NOW=2 CALL_LIST=1
274450mS CD: CALL: 254.1002.0 BState=Idle Cut=2 Music=3.0 Aend="Rob Johnson(213)" (0.0) Bend="" [] (0.0) CalledNum=9 () CallingNum=213 (Rob Johnson) Internal=1 Time=1586 AState=Dialling
274451mS CMMap: a=0.0 b=0.0 pcp[96]b0r1 RTPD1
275250mS PRN: 213: Digit Pressed 6
275250mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[6] Type=Default (100) Reason=CMDRdirect Keypad[6]
275251mS CMARS: FORM: Main - Received Number: 6
275251mS CMARS: CMARSTargetingPending
275252mS CMMap: a=0.0 b=0.0 pcp[96]b0r1 RTPD0
275499mS PRN: 213: Digit Pressed 5
275500mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect Keypad[5]
275500mS CMARS: FORM: Main - Received Number: 65
275500mS CMARS: CMARSTargetingPending
275772mS PRN: 213: Digit Pressed 1
275773mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
275773mS CMARS: FORM: Main - Received Number: 651
275774mS CMARS: CMARSTargetingPending
276033mS PRN: 213: Digit Pressed 2
276034mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[2] Type=Default (100) Reason=CMDRdirect Keypad[2]
276034mS CMARS: FORM: Main - Received Number: 6512
276034mS CMARS: CMARSTargetingPending
276278mS PRN: 213: Digit Pressed 3
276279mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[3] Type=Default (100) Reason=CMDRdirect Keypad[3]
276279mS CMARS: FORM: Main - Received Number: 65123
276280mS CMARS: CMARSTargetingPending
276559mS PRN: 213: Digit Pressed 8
276559mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[8] Type=Default (100) Reason=CMDRdirect Keypad[8]
276560mS CMARS: FORM: Main - Received Number: 651238
276560mS CMARS: CMARSTargetingPending
276830mS PRN: 213: Digit Pressed 5
276830mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect Keypad[5]
276831mS CMARS: FORM: Main - Received Number: 6512385
276831mS CMARS: CMARSTargetingPending
277199mS PRN: 213: Digit Pressed 6
277199mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[6] Type=Default (100) Reason=CMDRdirect Keypad[6]
277200mS CMARS: FORM: Main - Received Number: 65123856
277200mS CMARS: CMARSTargetingPending
277485mS PRN: 213: Digit Pressed 6
277486mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[6] Type=Default (100) Reason=CMDRdirect Keypad[6]
277486mS CMARS: FORM: Main - Received Number: 651238566
277486mS CMARS: CMARSTargetingPending
277772mS PRN: 213: Digit Pressed 3
277773mS CMExtnRx: v=213, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
Called[3] Type=Default (100) Reason=CMDRdirect Keypad[3]
277773mS CMARS: FORM: Main - Received Number: 6512385663
277774mS CMARS: CMARSTargetingPending
278925mS H323Evt: Recv: RegistrationRequest 192.168.100.242; Endpoints registered: 1; Endpoints in registration: 0
280486mS RES: Tue 1/11/2011 11:03:08 FreeMem=62386812(2) CMMsg=5 (6) Buff=5200 960 1000 7454 5 Links=6555
280487mS RES2: IP 500 8.0(3) Tasks=38 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=51 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 CF=UMNT
282443mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 6512385663 Addr: f528e788
282443mS CMARS: FORM: Main - Received Number: 6512385663
282443mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: N - Called_Party: 6512385663 - Line Group Id: 18
282443mS CMARS: FindActiveARSByGroupID GroupID=18 - Not Found
282444mS CMLRQ: FindActiveLRQByGroupID GroupID=18 - Not Found
282444mS Sip: License, Valid 1, Available 5, Consumed 0
282444mS CMCallEvt: 0.1005.0 -1 BaseEP: NEW CMEndpoint f528cc14 TOTAL NOW=3 CALL_LIST=1
282445mS Stun: Info: media ports will be mapped with STUN.
282448mS CMARS: FOUND LINE - Line Id: 18 - using line group id: 18 - Called Number: 6512385663 - Calling Number: 213
282449mS CMARS: SEND Setup TO LINE
282449mS CMCallEvt: 18.1005.0 1 SIPTrunk Endpoint: StateChange: END=child CMCSIdle->CMCSOffering
282450mS CMLineTx: v=0
CMSetup
Line: type=SIPLine 18 Call: lid=18 id=1005 in=0
Called[6512385663] Type=Default (100) Reason=CMDRdirect SndComp Calling[213] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Rob Johnson
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Rob Johnson
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Rob Johnson
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Rob Johnson
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=213
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=8 class=CMDeviceH323Phone type=110 number=8003 channel=0 rx_gain=32 tx_gain=32 ep_callid=1002 ipaddr=173.160.120.20 apps=0
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIEFastStartInfoData (6)
Locale: enu
282450mS Stun: Creating STUNClient to resolve binding for 173.160.120.20:49152, server 69.90.186.13:3478
282450mS Stun: Creating STUNUdpClient port 49152 address f5293068
282451mS Stun: StunClient: ResolveIPAndPort 173.160.120.20:49152
282451mS Stun: Creating STUNClient to resolve binding for 173.160.120.20:49153, server 69.90.186.13:3478
282451mS Stun: Creating STUNUdpClient port 49153 address f52921ac
282451mS Stun: StunClient: ResolveIPAndPort 173.160.120.20:49153
282452mS CD: CALL: 254.1002.0 BState=Idle Cut=2 Music=0.0 Aend="Rob Johnson(213)" (0.0) Bend="" [Line 18] (0.0) CalledNum=96512385663 () CallingNum=213 (Rob Johnson) Internal=1 Time=9588 AState=Dialling
283451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 0
283451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 0
284451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 1
284451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 1
286451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 2
286451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 2
290451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 3
290451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 3
298215mS SIP Reg/Opt Tx: 18
OPTIONS sip:Unknown@sp-udp01.iad.bandwidth.com SIP/2.0
Via: SIP/2.0/UDP 173.160.120.20:5060;rport;branch=z9hG4bK2718b5da0fcd047812e0ec02ec58181f
From: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=af115bfa8c5ad01b
To: <sip:Unknown@sp-udp01.iad.bandwidth.com>
Call-ID: b9368fe5be32b31781a629c12523d64c@173.160.120.20
CSeq: 1140286175 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0
298215mS SIP Tx: UDP 173.160.120.20:5060 -> 216.82.224.202:5060
OPTIONS sip:Unknown@sp-udp01.iad.bandwidth.com SIP/2.0
Via: SIP/2.0/UDP 173.160.120.20:5060;rport;branch=z9hG4bK2718b5da0fcd047812e0ec02ec58181f
From: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=af115bfa8c5ad01b
To: <sip:Unknown@sp-udp01.iad.bandwidth.com>
Call-ID: b9368fe5be32b31781a629c12523d64c@173.160.120.20
CSeq: 1140286175 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0
298253mS SIP Rx: UDP 216.82.224.202:5060 -> 173.160.120.20:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 173.160.120.20:5060;rport=5060;branch=z9hG4bK2718b5da0fcd047812e0ec02ec58181f
From: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=af115bfa8c5ad01b
To: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.238a
Call-ID: b9368fe5be32b31781a629c12523d64c@173.160.120.20
CSeq: 1140286175 OPTIONS
Server: Bandwidth.com TRM (bw7.gold.13)
Content-Length: 0
298255mS SIP Reg/Opt Rx: 18
SIP/2.0 200 OK
Via: SIP/2.0/UDP 173.160.120.20:5060;rport=5060;branch=z9hG4bK2718b5da0fcd047812e0ec02ec58181f
From: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=af115bfa8c5ad01b
To: <sip:Unknown@sp-udp01.iad.bandwidth.com>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.238a
Call-ID: b9368fe5be32b31781a629c12523d64c@173.160.120.20
CSeq: 1140286175 OPTIONS
Server: Bandwidth.com TRM (bw7.gold.13)
Content-Length: 0
298451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 4
298451mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 4
298486mS RES: Tue 1/11/2011 11:03:26 FreeMem=62348760(2) CMMsg=5 (6) Buff=5200 960 1000 7453 5 Links=6284
298487mS RES2: IP 500 8.0(3) Tasks=38 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=53 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 CF=UMNT
303486mS RES: Tue 1/11/2011 11:03:31 FreeMem=62361776(2) CMMsg=5 (6) Buff=5200 960 1000 7453 5 Links=6288
303487mS RES2: IP 500 8.0(3) Tasks=38 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=52 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 CF=UMNT
307523mS CMExtnTx: v=860, p1=8003
CMFacility
Line: type=IPLine 250 Call: lid=254 id=1 in=1
IE CMIEFastStartInfoData (6)
307523mS CMExtnRx: v=213, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
307524mS CMCallEvt: 254.1002.0 1 Rob Johnson.0: StateChange: END=A CMCSDialling->CMCSCompleted
307524mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Dialling,0,0,Rob Johnson
307525mS CMExtnTx: v=213, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=254 id=1002 in=0
IE CMIEFastStartInfoData (6)
Timed: 01/11/11 11:03
307527mS CMLOGGING: CALL:2011/11/0111:03,00:00:00,000,213,O,96512385663,96512385663,RobJohnson,,,1,,""n/a,0
307527mS CD: CALL: 254.1002.0 BState=Idle Cut=2 Music=0.0 Aend="Rob Johnson(213)" (0.0) Bend="" [Line 18] (0.0) CalledNum=96512385663 () CallingNum=213 (Rob Johnson) Internal=1 Time=34663 AState=Idle
307527mS CD: CALL: 254.1002.0 Deleted
307527mS CMExtnEvt: Rob Johnson: CALL LOST (CMCauseNormal)
307527mS CMExtnEvt: Rob Johnson: Extn(213) Calling Party Number(213) Type(CMNTypeInternal)
307528mS CMExtnEvt: Rob Johnson: CMExtnHandler::SetCurrent( id: 1002->0 )
307529mS CMCallEvt: 254.1002.0 -1 Rob Johnson.-1: StateChange: END=X CMCSCompleted->CMCSDelete
307529mS CMARS: CMARSEndpoint::CallLost(cause=16) - Address: 0.1004.0 1 ARS for Main - Call State: CMCSOverlapRecv
307530mS CMCallEvt: 18.1005.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSOffering->CMCSCompleted
307531mS CMCallEvt: 18.1005.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
307531mS Stun: ~STUNClient 173.160.120.20:49152
307531mS Stun: ~STUNClient 173.160.120.20:49153
307532mS CMCallEvt: 18.1005.0 -1 BaseEP: DELETE CMEndpoint f528cc14 TOTAL NOW=2 CALL_LIST=0
307532mS CMCallEvt: 0.1004.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
307533mS CMCallEvt: 0.1004.0 -1 BaseEP: DELETE CMEndpoint f528e788 TOTAL NOW=1 CALL_LIST=0
307533mS CMCallEvt: END CALL:1 (f529a3e0)
307534mS CMTARGET: 254.1002.0 -1 BaseEP: ~CMTargetHandler f5293150 ep f529af10
307534mS CMCallEvt: 254.1002.0 -1 BaseEP: DELETE CMEndpoint f529af10 TOTAL NOW=0 CALL_LIST=0
307536mS H323Evt: RTP(END): 192.168.100.240/1024 192.168.100.242/2166 CODEC=G729A8K(6) PKTSZ=20 RFC2833=off AGE=34662 SENT=119 RECV=110 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
309529mS CMExtnEvt: Rob Johnson: Recover Timer reason=CMTRWrapUp
309529mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Rob Johnson
309529mS CMExtnTx: v=213, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[Rob Johnson Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Rob Johnson Msgs=0]
Timed: 01/11/11 11:03
********** Warning: Logging to Screen Stopped **********