Avaya IPO 500V2 6.0.8
VCM32/PRI
DIG8/ATM4
DIG8
EMPTY
PRI IS CONFIGURED AS:
NI2
23>1
CLOCK QUALITY: NETWORK
CRC - CHECKED
CSU OPERATION - UNCHECKED
HAUL LENGTH - 0-115
LINE SUB TYPE - PRI
PROVIDER - LOCAL TELCO
FRAMING - ESF
ZERO SUPPRESSION - B8ZS
LINE SIGNALLING - CPE
INCOMING ROUTING DIGITS - 4
TELCO (CENTURYTEL/CENTURYLINK) IS OPERATING SIEMENS AWSD
IN NORTHERN WASHINGTON STATE, SAN JUAN ISLANDS
TRIED TO DIFFERENT AVAYA SYSTEMS - SAME RESULT
TRIED STRAIGHT T1 AND CROSSOVER CABLE - SAME RESULT
TELCO SAYS THEY SEE D CHANNEL UP, BUT CANNOT TELL ME IF THEY SEE MESSAGES PASSING BACK AND FORTH
CALLS BOTH DIRECTIONS FAIL - SEE TRACE BELOW:
UNICODE-UTF8
enu
********** SysMonitor v8.0 (8) **********
********** contact made with 192.168.120.11 at 16:05:13 10/6/2010 **********
********** System (192.168.120.11) has been up and running for 1min and 42secs(102238mS) **********
********** Warning: TEXT File Logging selected **********
********** Warning: TEXT Logging to File STOPPED on 10/6/2010 16:05:13 **********
102238mS PRN: Monitor Started IP=192.168.120.101 IP 500 V2 6.0(8) IPOffice_1
(IP Office: Supports Unicode, System Locale is enu)
102238mS PRN: LAW=U PRI=1, BRI=0, ALOG=4, ADSL=0 VCOMP=32, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=1 VMAIL=1(VER=2 TYP=3) CALLS=0(TOT=0)
102793mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
102793mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
103793mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
103793mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
104759mS ISDNL1Rx: v=1 peb=1
0000 02 01 7f ...
104759mS ISDNL2Rx: v=1 peb=1
0000 02 01 7f ...
104760mS ISDNL2Tx: v=1 peb=1
0000 02 01 73 ..s
104760mS ISDNL1Tx: v=1 peb=1
0000 02 01 73 ..s
106119mS ISDNL1Evt: v=1 peb=1,F3 F1
106125mS ISDNL1Evt: v=1 peb=1,UnLocked
106125mS PRN: Lock To Internal Clock, FPGA_REC_CLK_SELECT = 0x00
106238mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
106238mS PRN: + loader: 1.2
106238mS PRN: + cpu: id 12 board 2 pld 24 type c10 options a802
106238mS PRN: + fpga: id 1 issue 0 build 824
106238mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
106238mS PRN: ++++++++++++++++++++ LIST OF MODULES ++++++++++++++++++++
106238mS PRN: +------------------------------------------------------------
106239mS PRN: + Slot 1: Base VCM32 Board=0x05 PLD=0x11
106239mS PRN: + Mezzanine PRIS U Board=0x03 PLD=0x0a
106239mS PRN: +------------------------------------------------------------
106239mS PRN: + Slot 2: Base DIGSTA8 Board=0x13 PLD=0x06
106239mS PRN: + Mezzanine ATM4 Board=0x03 PLD=0x07
106239mS PRN: +------------------------------------------------------------
106240mS PRN: + Slot 3: Base DIGSTA8 Board=0x13 PLD=0x06
106240mS PRN: + Mezzanine NONE
106240mS PRN: +------------------------------------------------------------
106240mS PRN: + Slot 4: Base NONE
106240mS PRN: + Mezzanine NONE
106240mS PRN: +------------------------------------------------------------
106240mS PRN: +++++++++++++++++ END OF LIST OF MODULES +++++++++++++++
106480mS ISDNL1Evt: v=1 peb=1,F1 F3
108120mS ISDNL1Evt: v=1 peb=1,F3 F1
108301mS ISDNL1Evt: v=1 peb=1,F1 F3
108340mS ISDNL1Evt: v=1 peb=1,F3 F1
108808mS ISDNL1Evt: v=1 peb=1,F1 F3
110140mS ISDNL1Evt: v=1 peb=1,F3 F1
110635mS ISDNL1Evt: v=1 peb=1,F1 F3
117419mS PRN: Falc 1 slip
117419mS PRN: WARNING:
117419mS PRN: ****Reset Slip Store Falc 1 2 0
117419mS PRN:
117420mS PRN: WARNING:
117420mS PRN: 16:05:24
117420mS PRN:
118135mS ISDNL1Evt: v=1 peb=1,F3 F1
118153mS ISDNL1Evt: v=1 peb=1,F1 F3
119143mS ISDNL1Evt: v=1 peb=1,F3 F1
119359mS ISDNL1Evt: v=1 peb=1,F1 F3
122758mS RES: Thu 10/6/2010 16:05:29 FreeMem=70176424(2) CMMsg=2 (3) Buff=200 952 1000 7376 5 Links=4519
122758mS RES2: IP 500 V2 6.0(8) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=42 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
124120mS ISDNL1Evt: v=1 peb=1,F3 F1
124300mS ISDNL1Evt: v=1 peb=1,F1 F3
126099mS CMCallEvt: 0.1002.0 -1 BaseEP: NEW CMEndpoint f55b3a08 TOTAL NOW=1 CALL_LIST=0
126100mS CMCallEvt: 0.1002.0 -1 Extn204.-1: NEW CMExtnEndpoint f55b3a08, Name=Extn204, Extn=204, Phys Extn=204
126100mS CMCallEvt: CREATE CALL:1 (f55b35ac)
126100mS CMCallEvt: 0.1003.0 -1 BaseEP: NEW CMEndpoint f55b2198 TOTAL NOW=2 CALL_LIST=0
126102mS CMExtnEvt: Extn204: CMExtnHandler::SetCurrent( id: 0->1002 )
126102mS CMExtnRx: v=204, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[204] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn204
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Extn204
126103mS CMCallEvt: 0.1002.0 1 Extn204.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
126103mS CMExtnEvt: v=4 State, new=Connected old=Idle,0,0,Extn204
126103mS CMTARGET: 0.1002.0 1 Extn204.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=204 dir=out complete=0 ses=0
126103mS CMTARGET: 0.1002.0 1 Extn204.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
126104mS CMCallEvt: 0.1002.0 1 Extn204.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
126104mS CMExtnEvt: v=4 State, new=Dialling old=Connected,0,0,Extn204
126104mS CMExtnTx: v=204, p1=0
CMSetupAck
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
BChan: slot=20 chan=4
Timed: 10/06/10 16:05
126105mS CD: CALL: 0.1002.0 BState=Idle Cut=1 Music=3.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum= () CallingNum=204 (Extn204) Internal=1 Time=5 AState=DialInitiated
126106mS CMMap: a=20.4 b=0.0 D1
126121mS ISDNL1Evt: v=1 peb=1,F3 F1
126621mS ISDNL1Evt: v=1 peb=1,PHDI
126859mS ISDNL1Evt: v=1 peb=1,F1 F3
126909mS ISDNL1Evt: v=1 peb=1,PHAI
127223mS PRN: 204: Digit Pressed 9
127223mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
127223mS CMTARGET: 0.1002.0 1 Extn204.0: Setting Hard Timer 4000
127224mS CMTARGET: 0.1002.0 1 Extn204.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=204 dir=out complete=0 ses=0
127224mS CMTARGET: 0.1002.0 1 Extn204.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
127224mS CMTARGET: 0.1002.0 1 Extn204.0: SYS SC: 9 2 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
127225mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
127225mS CMARS: MakeCallTarget - Called Number:
127225mS CMCallEvt: 0.1004.0 -1 BaseEP: NEW CMEndpoint f55a946c TOTAL NOW=3 CALL_LIST=1
127226mS CMTARGET: 0.1002.0 1 Extn204.0: CancelTimer CMTCDelayedProcessing
127226mS CMTARGET: 0.1002.0 1 Extn204.0: INITIAL TARGETING SUCCEEDED
127226mS CMTARGET: 0.1002.0 1 Extn204.0: GetNoAnswerTimer:15
127227mS CMCallEvt: 0.1003.0 1 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
127227mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
127228mS CMCallEvt: 0.1003.0 1 TargetingEP: RequestEnd 0.1004.0 1 ARS for Main
127228mS CMTARGET: 0.1002.0 1 Extn204.0: CancelTimer CMTCNoAnswerTimeout
127228mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
127229mS CMExtnTx: v=204, p1=0
CMProgress
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [TRANSFERABLE_SEC_DIAL_TONE]
Timed: 10/06/10 16:05
127229mS CMARS: FORM: Main - Received Number:
127229mS CMARS: CMARSTargetingPending
127229mS CMCallEvt: 0.1003.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
127229mS CMCallEvt: 0.1003.0 -1 BaseEP: DELETE CMEndpoint f55b2198 TOTAL NOW=2 CALL_LIST=1
127230mS CD: CALL: 0.1002.0 BState=Idle Cut=2 Music=3.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum=9 () CallingNum=204 (Extn204) Internal=1 Time=1130 AState=Dialling
127231mS CMMap: a=20.4 b=0.0 D1
127509mS PRN: 204: Digit Pressed 1
127509mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
127510mS CMARS: FORM: Main - Received Number: 1
127510mS CMARS: CMARSTargetingPending
127511mS CMMap: a=20.4 b=0.0 D0
127684mS PRN: 204: Digit Pressed 3
127684mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
127685mS CMARS: FORM: Main - Received Number: 13
127685mS CMARS: CMARSTargetingPending
127859mS PRN: 204: Digit Pressed 1
127859mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
127860mS CMARS: FORM: Main - Received Number: 131
127860mS CMARS: CMARSTargetingPending
128074mS PRN: 204: Digit Pressed 0
128074mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
128075mS CMARS: FORM: Main - Received Number: 1310
128075mS CMARS: CMARSTargetingPending
129322mS PRN: 204: Digit Pressed 7
129322mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
129323mS CMARS: FORM: Main - Received Number: 13107
129323mS CMARS: CMARSTargetingPending
129511mS PRN: 204: Digit Pressed 7
129511mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
129512mS CMARS: FORM: Main - Received Number: 131077
129512mS CMARS: CMARSTargetingPending
129685mS PRN: 204: Digit Pressed 7
129685mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
129686mS CMARS: FORM: Main - Received Number: 1310777
129686mS CMARS: CMARSTargetingPending
129897mS PRN: 204: Digit Pressed 4
129897mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[4] Type=Default (100) Reason=CMDRdirect
129898mS CMARS: FORM: Main - Received Number: 13107774
129898mS CMARS: CMARSTargetingPending
130072mS PRN: 204: Digit Pressed 5
130072mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
130073mS CMARS: FORM: Main - Received Number: 131077745
130073mS CMARS: CMARSTargetingPending
130141mS ISDNL1Evt: v=1 peb=1,F3 F1
130249mS ISDNL1Evt: v=1 peb=1,F1 F3
130259mS PRN: 204: Digit Pressed 0
130259mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
130260mS CMARS: FORM: Main - Received Number: 1310777450
130260mS CMARS: CMARSTargetingPending
130422mS PRN: 204: Digit Pressed 0
130422mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
130423mS CMARS: FORM: Main - Received Number: 13107774500
130423mS CMARS: CMARSTargetingPending
131766mS ISDNL1Evt: v=1 peb=1,F3 F1
132266mS ISDNL1Evt: v=1 peb=1,PHDI
132745mS ISDNL1Evt: v=1 peb=1,F1 F3
132767mS ISDNL1Evt: v=1 peb=1,F3 F1
133771mS ISDNL1Evt: v=1 peb=1,F1 F3
133821mS ISDNL1Evt: v=1 peb=1,PHAI
134760mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
134760mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
134766mS ISDNL1Rx: v=1 peb=1
0000 02 01 7f ...
134766mS ISDNL2Rx: v=1 peb=1
0000 02 01 7f ...
134766mS ISDNL2Tx: v=1 peb=1
0000 02 01 73 ..s
134766mS ISDNL1Tx: v=1 peb=1
0000 02 01 73 ..s
135225mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 13107774500 Addr: f55a946c
135225mS CMARS: FORM: Main - Received Number: 13107774500
135225mS CMARS: FOUND A SHORT CODE - short_code: 1N; - Tel: 1N - Called_Party: 13107774500 - Line Group Id: 0
135226mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
135226mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
135226mS CMCallEvt: 0.1005.0 -1 BaseEP: NEW CMEndpoint f55a78cc TOTAL NOW=3 CALL_LIST=1
135227mS PRN: ObjectMemoryPool: SSIComponentId, filled 5 times
135228mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 13107774500 - Calling Number: 204
135228mS CMARS: SEND Setup TO LINE
135229mS CMCallEvt: 0.1005.0 1 Q931 Trunk:1 CHAN=8: StateChange: END=child CMCSIdle->CMCSOffering
135229mS PRN: Setting configured voice gain for ch 8.
135229mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[13107774500] Type=National (2) Reason=CMDRdirect Calling[] Type=National Plan=ISDN
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=8
Locale: enu
135229mS CD: CALL: 0.1002.0 BState=Idle Cut=2 Music=0.0 Aend="Extn204(204)" (20.4) Bend="" [Line 1] (0.0) CalledNum=913107774500 () CallingNum=204 (Extn204) Internal=1 Time=9129 AState=Dialling
135231mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=1005
135231mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 90 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 88 .....
InformationElement = CalledPartyNumber
0000 70 0c a0 31 33 31 30 37 37 37 34 35 30 30 p..13107774500
135231mS ISDNL2Tx: v=1 peb=1
0000 00 01 00 00 08 02 00 01 05 04 03 90 90 a2 18 03 ................
0010 a9 83 88 70 0c a0 31 33 31 30 37 37 37 34 35 30 ...p..1310777450
0020 30 0
135231mS ISDNL1Tx: v=1 peb=1
0000 00 01 00 00 08 02 00 01 05 04 03 90 90 a2 18 03 ................
0010 a9 83 88 70 0c a0 31 33 31 30 37 37 37 34 35 30 ...p..1310777450
0020 30 0
135257mS RES: Thu 10/6/2010 16:05:41 FreeMem=70105492(2) CMMsg=4 (5) Buff=200 952 1000 7376 5 Links=3628
135257mS RES2: IP 500 V2 6.0(8) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=44 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
136231mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
136231mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
137224mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 03 ....
137224mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 03 ....
137224mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 01 ....
137224mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 01 ....
137231mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
137231mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
138231mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
138231mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
139231mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 90 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 88 .....
InformationElement = CalledPartyNumber
0000 70 0c a0 31 33 31 30 37 37 37 34 35 30 30 p..13107774500
139231mS ISDNL2Tx: v=1 peb=1
0000 00 01 7f ...
139232mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
139232mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=1005
139232mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Cause=38, Network out of order
139233mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
139233mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Cause=16, Normal call clearing
139234mS CMCallEvt: 0.1005.0 -1 Q931 Trunk:1 CHAN=8: StateChange: END=X CMCSOffering->CMCSCompleted
139234mS CMARS: Target: Short_Code: 1N; - Line_Group_ID: 0 has been set to: CMARS_OUTOFSERVICE
139234mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=B CMCSOverlapRecv->CMCSAccept
139234mS CMCallEvt: 0.1002.0 1 Extn204.0: StateChange: END=A CMCSDialling->CMCSRingBack
139235mS CMExtnEvt: v=4 State, new=Alerting old=Dialling,0,0,Extn204
139235mS CMExtnTx: v=204, p1=0
CMProgress
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [WAITING_FOR_LINE]
Timed: 10/06/10 16:05
139235mS CD: CALL: 0.1002.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum=913107774500 () CallingNum=204 (Extn204) Internal=1 Time=13135 AState=Ringing
139236mS ISDNL1Tx: v=1 peb=1
0000 00 01 7f ...
139236mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
139237mS CMMap: a=21.39 b=1.255 T
139237mS CMMap: PCG::MapBChan pcp[122]b1r0 cp_b f56246c0 other_cp_b f5a7809c type CGTypeDTMF
139237mS CMMap: a=21.39 b=20.4 M2
139237mS CMMap: DTMF:
layTones g T[15] ,EEEEE
139237mS CMMap: Started playing tones, cp[122]b1r0 (from g null)
139237mS CMMap: cp[122]b1r0 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
140231mS ISDNL2Tx: v=1 peb=1
0000 00 01 7f ...
140231mS ISDNL1Tx: v=1 peb=1
0000 00 01 7f ...
140234mS CMARS: Retarget existing BUSY targets - Num of Busy Targets: 1 - Current Form: Main
140257mS RES: Thu 10/6/2010 16:05:46 FreeMem=70101608(2) CMMsg=4 (5) Buff=200 952 1000 7374 5 Links=3613
140257mS RES2: IP 500 V2 6.0(8) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
141058mS CMMap: PCG::UnmapBChan pcp[122]b1r0 cp_b f56246c0 other_cp_b f5a7809c
141058mS CMMap: a=21.39 b=20.4 M0
141058mS CMMap: a=21.39 b=0.0 T0
141058mS CMMap: a=21.39 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
141059mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
141059mS CMMap: a=21.39 b=1.255 T
141059mS CMMap: PCG::MapBChan pcp[122]b1r0 cp_b f56246c0 other_cp_b f5a7809c type CGTypeDTMF
141059mS CMMap: a=21.39 b=20.4 M2
141059mS CMMap: DTMF:layTones g T[16] ,EEEEE
141059mS CMMap: Started playing tones, cp[122]b1r0 (from g null)
141060mS CMMap: cp[122]b1r0 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
141231mS ISDNL2Tx: v=1 peb=1
0000 00 01 7f ...
141231mS ISDNL1Tx: v=1 peb=1
0000 00 01 7f ...
142231mS ISDNL2Tx: v=1 peb=1
0000 00 01 7f ...
142231mS ISDNL1Tx: v=1 peb=1
0000 00 01 7f ...
142880mS CMMap: PCG::UnmapBChan pcp[122]b1r0 cp_b f56246c0 other_cp_b f5a7809c
142880mS CMMap: a=21.39 b=20.4 M0
142880mS CMMap: a=21.39 b=0.0 T0
142880mS CMMap: a=21.39 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
143231mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
143771mS ISDNL1Evt: v=1 peb=1,Locked
143771mS PRN: Falc: LockToFalc: 1
143771mS PRN: MEZZ_PRI_CLOCK_CNTL: f1
143771mS PRN: FPGA_REC_CLK_SELECT: 08
143771mS PRN: SetSlaveMode: 01
146173mS CMExtnRx: v=204, p1=0
CMReleaseComp
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
146173mS CMCallEvt: 0.1002.0 1 Extn204.0: StateChange: END=A CMCSRingBack->CMCSCompleted
146174mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Alerting,0,0,Extn204
146175mS CMLOGGING: CALL:2010/06/1016:05,00:00:00,000,204,O,913107774500,913107774500,Extn204,,,1,,""n/a,0
146175mS CD: CALL: 0.1002.0 BState=Ringing Cut=2 Music=0.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum=913107774500 () CallingNum=204 (Extn204) Internal=1 Time=20075 AState=Idle
146175mS CD: CALL: 0.1002.0 Deleted
146175mS CMExtnEvt: Extn204: CALL LOST (CMCauseNormal)
146176mS CMExtnEvt: Extn204: Extn(204) Calling Party Number(204) Type(CMNTypeInternal)
146176mS CMExtnEvt: Extn204: CMExtnHandler::SetCurrent( id: 1002->0 )
146176mS CMCallEvt: 0.1002.0 -1 Extn204.-1: StateChange: END=X CMCSCompleted->CMCSDelete
146176mS CMARS: CMARSEndpoint::CallLost(cause=16) - Address: 0.1004.0 1 ARS for Main - Call State: CMCSAccept
146177mS CMCallEvt: 0.1004.0 -1 ARS for Main: StateChange: END=X CMCSAccept->CMCSDelete
146177mS CMCallEvt: 0.1004.0 -1 BaseEP: DELETE CMEndpoint f55a946c TOTAL NOW=2 CALL_LIST=0
146177mS CMCallEvt: END CALL:1 (f55b35ac)
146178mS CMTARGET: 0.1002.0 -1 BaseEP: ~CMTargetHandler f55acae4 ep f55b3a08
146178mS CMCallEvt: 0.1002.0 -1 BaseEP: DELETE CMEndpoint f55b3a08 TOTAL NOW=1 CALL_LIST=0
146258mS RES: Thu 10/6/2010 16:05:52 FreeMem=70129572(2) CMMsg=4 (5) Buff=200 952 1000 7376 5 Links=3624
146258mS RES2: IP 500 V2 6.0(8) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=44 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
147336mS CMCallEvt: 0.1006.0 -1 BaseEP: NEW CMEndpoint f55b3a08 TOTAL NOW=2 CALL_LIST=0
147336mS CMCallEvt: 0.1006.0 -1 Extn204.-1: NEW CMExtnEndpoint f55b3a08, Name=Extn204, Extn=204, Phys Extn=204
147336mS CMCallEvt: CREATE CALL:2 (f55b35ac)
147336mS CMCallEvt: 0.1007.0 -1 BaseEP: NEW CMEndpoint f55b2198 TOTAL NOW=3 CALL_LIST=0
147338mS CMExtnEvt: Extn204: CMExtnHandler::SetCurrent( id: 0->1006 )
147338mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Extn204
147338mS CMExtnRx: v=204, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=1006 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[204] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn204
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Extn204
147339mS CMCallEvt: 0.1006.0 2 Extn204.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
147339mS CMExtnEvt: v=4 State, new=Connected old=Idle,0,0,Extn204
147339mS CMTARGET: 0.1006.0 2 Extn204.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=204 dir=out complete=0 ses=0
147339mS CMTARGET: 0.1006.0 2 Extn204.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
147340mS CMCallEvt: 0.1006.0 2 Extn204.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
147340mS CMExtnEvt: v=4 State, new=Dialling old=Connected,0,0,Extn204
147340mS CMExtnTx: v=204, p1=0
CMSetupAck
Line: type=DigitalExtn 3 Call: lid=0 id=1006 in=0
BChan: slot=20 chan=4
Timed: 10/06/10 16:05
147341mS CD: CALL: 0.1006.0 BState=Idle Cut=1 Music=3.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum= () CallingNum=204 (Extn204) Internal=1 Time=5 AState=DialInitiated
147342mS CMMap: a=20.4 b=0.0 D1
150035mS CMExtnRx: v=204, p1=0
CMReleaseComp
Line: type=DigitalExtn 3 Call: lid=0 id=1006 in=0
150035mS CMCallEvt: 0.1006.0 2 Extn204.0: StateChange: END=A CMCSDialling->CMCSCompleted
150036mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Dialling,0,0,Extn204
150036mS CMLOGGING: CALL:2010/06/1016:05,00:00:00,000,204,O,,,Extn204,,,1,,""n/a,0
150037mS CD: CALL: 0.1006.0 BState=Idle Cut=0 Music=0.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum= () CallingNum=204 (Extn204) Internal=1 Time=2701 AState=Idle
150037mS CD: CALL: 0.1006.0 Deleted
150037mS CMExtnEvt: Extn204: CALL LOST (CMCauseNormal)
150037mS CMExtnEvt: Extn204: Extn(204) Calling Party Number(204) Type(CMNTypeInternal)
150038mS CMExtnEvt: Extn204: CMExtnHandler::SetCurrent( id: 1006->0 )
150038mS CMCallEvt: 0.1006.0 -1 Extn204.-1: StateChange: END=X CMCSCompleted->CMCSDelete
150038mS CMCallEvt: 0.1007.0 -1 BaseEP: DELETE CMEndpoint f55b2198 TOTAL NOW=2 CALL_LIST=0
150038mS CMCallEvt: END CALL:2 (f55b35ac)
150039mS CMTARGET: 0.1006.0 -1 BaseEP: ~CMTargetHandler f55acae4 ep f55b3a08
150039mS CMCallEvt: 0.1006.0 -1 BaseEP: DELETE CMEndpoint f55b3a08 TOTAL NOW=1 CALL_LIST=0
150040mS CMMap: a=20.4 b=0.0 D0
152038mS CMExtnEvt: Extn204: Recover Timer reason=CMTRWrapUp
152038mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Extn204
152039mS CMExtnTx: v=204, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Extn204 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn204 Msgs=0]
Timed: 10/06/10 16:05
********** Warning: Logging to Screen Stopped **********
VCM32/PRI
DIG8/ATM4
DIG8
EMPTY
PRI IS CONFIGURED AS:
NI2
23>1
CLOCK QUALITY: NETWORK
CRC - CHECKED
CSU OPERATION - UNCHECKED
HAUL LENGTH - 0-115
LINE SUB TYPE - PRI
PROVIDER - LOCAL TELCO
FRAMING - ESF
ZERO SUPPRESSION - B8ZS
LINE SIGNALLING - CPE
INCOMING ROUTING DIGITS - 4
TELCO (CENTURYTEL/CENTURYLINK) IS OPERATING SIEMENS AWSD
IN NORTHERN WASHINGTON STATE, SAN JUAN ISLANDS
TRIED TO DIFFERENT AVAYA SYSTEMS - SAME RESULT
TRIED STRAIGHT T1 AND CROSSOVER CABLE - SAME RESULT
TELCO SAYS THEY SEE D CHANNEL UP, BUT CANNOT TELL ME IF THEY SEE MESSAGES PASSING BACK AND FORTH
CALLS BOTH DIRECTIONS FAIL - SEE TRACE BELOW:
UNICODE-UTF8
enu
********** SysMonitor v8.0 (8) **********
********** contact made with 192.168.120.11 at 16:05:13 10/6/2010 **********
********** System (192.168.120.11) has been up and running for 1min and 42secs(102238mS) **********
********** Warning: TEXT File Logging selected **********
********** Warning: TEXT Logging to File STOPPED on 10/6/2010 16:05:13 **********
102238mS PRN: Monitor Started IP=192.168.120.101 IP 500 V2 6.0(8) IPOffice_1
(IP Office: Supports Unicode, System Locale is enu)
102238mS PRN: LAW=U PRI=1, BRI=0, ALOG=4, ADSL=0 VCOMP=32, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=1 VMAIL=1(VER=2 TYP=3) CALLS=0(TOT=0)
102793mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
102793mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
103793mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
103793mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
104759mS ISDNL1Rx: v=1 peb=1
0000 02 01 7f ...
104759mS ISDNL2Rx: v=1 peb=1
0000 02 01 7f ...
104760mS ISDNL2Tx: v=1 peb=1
0000 02 01 73 ..s
104760mS ISDNL1Tx: v=1 peb=1
0000 02 01 73 ..s
106119mS ISDNL1Evt: v=1 peb=1,F3 F1
106125mS ISDNL1Evt: v=1 peb=1,UnLocked
106125mS PRN: Lock To Internal Clock, FPGA_REC_CLK_SELECT = 0x00
106238mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
106238mS PRN: + loader: 1.2
106238mS PRN: + cpu: id 12 board 2 pld 24 type c10 options a802
106238mS PRN: + fpga: id 1 issue 0 build 824
106238mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
106238mS PRN: ++++++++++++++++++++ LIST OF MODULES ++++++++++++++++++++
106238mS PRN: +------------------------------------------------------------
106239mS PRN: + Slot 1: Base VCM32 Board=0x05 PLD=0x11
106239mS PRN: + Mezzanine PRIS U Board=0x03 PLD=0x0a
106239mS PRN: +------------------------------------------------------------
106239mS PRN: + Slot 2: Base DIGSTA8 Board=0x13 PLD=0x06
106239mS PRN: + Mezzanine ATM4 Board=0x03 PLD=0x07
106239mS PRN: +------------------------------------------------------------
106240mS PRN: + Slot 3: Base DIGSTA8 Board=0x13 PLD=0x06
106240mS PRN: + Mezzanine NONE
106240mS PRN: +------------------------------------------------------------
106240mS PRN: + Slot 4: Base NONE
106240mS PRN: + Mezzanine NONE
106240mS PRN: +------------------------------------------------------------
106240mS PRN: +++++++++++++++++ END OF LIST OF MODULES +++++++++++++++
106480mS ISDNL1Evt: v=1 peb=1,F1 F3
108120mS ISDNL1Evt: v=1 peb=1,F3 F1
108301mS ISDNL1Evt: v=1 peb=1,F1 F3
108340mS ISDNL1Evt: v=1 peb=1,F3 F1
108808mS ISDNL1Evt: v=1 peb=1,F1 F3
110140mS ISDNL1Evt: v=1 peb=1,F3 F1
110635mS ISDNL1Evt: v=1 peb=1,F1 F3
117419mS PRN: Falc 1 slip
117419mS PRN: WARNING:
117419mS PRN: ****Reset Slip Store Falc 1 2 0
117419mS PRN:
117420mS PRN: WARNING:
117420mS PRN: 16:05:24
117420mS PRN:
118135mS ISDNL1Evt: v=1 peb=1,F3 F1
118153mS ISDNL1Evt: v=1 peb=1,F1 F3
119143mS ISDNL1Evt: v=1 peb=1,F3 F1
119359mS ISDNL1Evt: v=1 peb=1,F1 F3
122758mS RES: Thu 10/6/2010 16:05:29 FreeMem=70176424(2) CMMsg=2 (3) Buff=200 952 1000 7376 5 Links=4519
122758mS RES2: IP 500 V2 6.0(8) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=42 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
124120mS ISDNL1Evt: v=1 peb=1,F3 F1
124300mS ISDNL1Evt: v=1 peb=1,F1 F3
126099mS CMCallEvt: 0.1002.0 -1 BaseEP: NEW CMEndpoint f55b3a08 TOTAL NOW=1 CALL_LIST=0
126100mS CMCallEvt: 0.1002.0 -1 Extn204.-1: NEW CMExtnEndpoint f55b3a08, Name=Extn204, Extn=204, Phys Extn=204
126100mS CMCallEvt: CREATE CALL:1 (f55b35ac)
126100mS CMCallEvt: 0.1003.0 -1 BaseEP: NEW CMEndpoint f55b2198 TOTAL NOW=2 CALL_LIST=0
126102mS CMExtnEvt: Extn204: CMExtnHandler::SetCurrent( id: 0->1002 )
126102mS CMExtnRx: v=204, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[204] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn204
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Extn204
126103mS CMCallEvt: 0.1002.0 1 Extn204.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
126103mS CMExtnEvt: v=4 State, new=Connected old=Idle,0,0,Extn204
126103mS CMTARGET: 0.1002.0 1 Extn204.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=204 dir=out complete=0 ses=0
126103mS CMTARGET: 0.1002.0 1 Extn204.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
126104mS CMCallEvt: 0.1002.0 1 Extn204.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
126104mS CMExtnEvt: v=4 State, new=Dialling old=Connected,0,0,Extn204
126104mS CMExtnTx: v=204, p1=0
CMSetupAck
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
BChan: slot=20 chan=4
Timed: 10/06/10 16:05
126105mS CD: CALL: 0.1002.0 BState=Idle Cut=1 Music=3.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum= () CallingNum=204 (Extn204) Internal=1 Time=5 AState=DialInitiated
126106mS CMMap: a=20.4 b=0.0 D1
126121mS ISDNL1Evt: v=1 peb=1,F3 F1
126621mS ISDNL1Evt: v=1 peb=1,PHDI
126859mS ISDNL1Evt: v=1 peb=1,F1 F3
126909mS ISDNL1Evt: v=1 peb=1,PHAI
127223mS PRN: 204: Digit Pressed 9
127223mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
127223mS CMTARGET: 0.1002.0 1 Extn204.0: Setting Hard Timer 4000
127224mS CMTARGET: 0.1002.0 1 Extn204.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=204 dir=out complete=0 ses=0
127224mS CMTARGET: 0.1002.0 1 Extn204.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
127224mS CMTARGET: 0.1002.0 1 Extn204.0: SYS SC: 9 2 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
127225mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
127225mS CMARS: MakeCallTarget - Called Number:
127225mS CMCallEvt: 0.1004.0 -1 BaseEP: NEW CMEndpoint f55a946c TOTAL NOW=3 CALL_LIST=1
127226mS CMTARGET: 0.1002.0 1 Extn204.0: CancelTimer CMTCDelayedProcessing
127226mS CMTARGET: 0.1002.0 1 Extn204.0: INITIAL TARGETING SUCCEEDED
127226mS CMTARGET: 0.1002.0 1 Extn204.0: GetNoAnswerTimer:15
127227mS CMCallEvt: 0.1003.0 1 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
127227mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
127228mS CMCallEvt: 0.1003.0 1 TargetingEP: RequestEnd 0.1004.0 1 ARS for Main
127228mS CMTARGET: 0.1002.0 1 Extn204.0: CancelTimer CMTCNoAnswerTimeout
127228mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
127229mS CMExtnTx: v=204, p1=0
CMProgress
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [TRANSFERABLE_SEC_DIAL_TONE]
Timed: 10/06/10 16:05
127229mS CMARS: FORM: Main - Received Number:
127229mS CMARS: CMARSTargetingPending
127229mS CMCallEvt: 0.1003.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
127229mS CMCallEvt: 0.1003.0 -1 BaseEP: DELETE CMEndpoint f55b2198 TOTAL NOW=2 CALL_LIST=1
127230mS CD: CALL: 0.1002.0 BState=Idle Cut=2 Music=3.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum=9 () CallingNum=204 (Extn204) Internal=1 Time=1130 AState=Dialling
127231mS CMMap: a=20.4 b=0.0 D1
127509mS PRN: 204: Digit Pressed 1
127509mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
127510mS CMARS: FORM: Main - Received Number: 1
127510mS CMARS: CMARSTargetingPending
127511mS CMMap: a=20.4 b=0.0 D0
127684mS PRN: 204: Digit Pressed 3
127684mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
127685mS CMARS: FORM: Main - Received Number: 13
127685mS CMARS: CMARSTargetingPending
127859mS PRN: 204: Digit Pressed 1
127859mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
127860mS CMARS: FORM: Main - Received Number: 131
127860mS CMARS: CMARSTargetingPending
128074mS PRN: 204: Digit Pressed 0
128074mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
128075mS CMARS: FORM: Main - Received Number: 1310
128075mS CMARS: CMARSTargetingPending
129322mS PRN: 204: Digit Pressed 7
129322mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
129323mS CMARS: FORM: Main - Received Number: 13107
129323mS CMARS: CMARSTargetingPending
129511mS PRN: 204: Digit Pressed 7
129511mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
129512mS CMARS: FORM: Main - Received Number: 131077
129512mS CMARS: CMARSTargetingPending
129685mS PRN: 204: Digit Pressed 7
129685mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
129686mS CMARS: FORM: Main - Received Number: 1310777
129686mS CMARS: CMARSTargetingPending
129897mS PRN: 204: Digit Pressed 4
129897mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[4] Type=Default (100) Reason=CMDRdirect
129898mS CMARS: FORM: Main - Received Number: 13107774
129898mS CMARS: CMARSTargetingPending
130072mS PRN: 204: Digit Pressed 5
130072mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
130073mS CMARS: FORM: Main - Received Number: 131077745
130073mS CMARS: CMARSTargetingPending
130141mS ISDNL1Evt: v=1 peb=1,F3 F1
130249mS ISDNL1Evt: v=1 peb=1,F1 F3
130259mS PRN: 204: Digit Pressed 0
130259mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
130260mS CMARS: FORM: Main - Received Number: 1310777450
130260mS CMARS: CMARSTargetingPending
130422mS PRN: 204: Digit Pressed 0
130422mS CMExtnRx: v=204, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
130423mS CMARS: FORM: Main - Received Number: 13107774500
130423mS CMARS: CMARSTargetingPending
131766mS ISDNL1Evt: v=1 peb=1,F3 F1
132266mS ISDNL1Evt: v=1 peb=1,PHDI
132745mS ISDNL1Evt: v=1 peb=1,F1 F3
132767mS ISDNL1Evt: v=1 peb=1,F3 F1
133771mS ISDNL1Evt: v=1 peb=1,F1 F3
133821mS ISDNL1Evt: v=1 peb=1,PHAI
134760mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
134760mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
134766mS ISDNL1Rx: v=1 peb=1
0000 02 01 7f ...
134766mS ISDNL2Rx: v=1 peb=1
0000 02 01 7f ...
134766mS ISDNL2Tx: v=1 peb=1
0000 02 01 73 ..s
134766mS ISDNL1Tx: v=1 peb=1
0000 02 01 73 ..s
135225mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 13107774500 Addr: f55a946c
135225mS CMARS: FORM: Main - Received Number: 13107774500
135225mS CMARS: FOUND A SHORT CODE - short_code: 1N; - Tel: 1N - Called_Party: 13107774500 - Line Group Id: 0
135226mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
135226mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
135226mS CMCallEvt: 0.1005.0 -1 BaseEP: NEW CMEndpoint f55a78cc TOTAL NOW=3 CALL_LIST=1
135227mS PRN: ObjectMemoryPool: SSIComponentId, filled 5 times
135228mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 13107774500 - Calling Number: 204
135228mS CMARS: SEND Setup TO LINE
135229mS CMCallEvt: 0.1005.0 1 Q931 Trunk:1 CHAN=8: StateChange: END=child CMCSIdle->CMCSOffering
135229mS PRN: Setting configured voice gain for ch 8.
135229mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[13107774500] Type=National (2) Reason=CMDRdirect Calling[] Type=National Plan=ISDN
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=8
Locale: enu
135229mS CD: CALL: 0.1002.0 BState=Idle Cut=2 Music=0.0 Aend="Extn204(204)" (20.4) Bend="" [Line 1] (0.0) CalledNum=913107774500 () CallingNum=204 (Extn204) Internal=1 Time=9129 AState=Dialling
135231mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=1005
135231mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 90 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 88 .....
InformationElement = CalledPartyNumber
0000 70 0c a0 31 33 31 30 37 37 37 34 35 30 30 p..13107774500
135231mS ISDNL2Tx: v=1 peb=1
0000 00 01 00 00 08 02 00 01 05 04 03 90 90 a2 18 03 ................
0010 a9 83 88 70 0c a0 31 33 31 30 37 37 37 34 35 30 ...p..1310777450
0020 30 0
135231mS ISDNL1Tx: v=1 peb=1
0000 00 01 00 00 08 02 00 01 05 04 03 90 90 a2 18 03 ................
0010 a9 83 88 70 0c a0 31 33 31 30 37 37 37 34 35 30 ...p..1310777450
0020 30 0
135257mS RES: Thu 10/6/2010 16:05:41 FreeMem=70105492(2) CMMsg=4 (5) Buff=200 952 1000 7376 5 Links=3628
135257mS RES2: IP 500 V2 6.0(8) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=44 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
136231mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
136231mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
137224mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 03 ....
137224mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 03 ....
137224mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 01 ....
137224mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 01 ....
137231mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
137231mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
138231mS ISDNL2Tx: v=1 peb=1
0000 00 01 01 01 ....
138231mS ISDNL1Tx: v=1 peb=1
0000 00 01 01 01 ....
139231mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 90 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 88 .....
InformationElement = CalledPartyNumber
0000 70 0c a0 31 33 31 30 37 37 37 34 35 30 30 p..13107774500
139231mS ISDNL2Tx: v=1 peb=1
0000 00 01 7f ...
139232mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
139232mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=1005
139232mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Cause=38, Network out of order
139233mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
139233mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Cause=16, Normal call clearing
139234mS CMCallEvt: 0.1005.0 -1 Q931 Trunk:1 CHAN=8: StateChange: END=X CMCSOffering->CMCSCompleted
139234mS CMARS: Target: Short_Code: 1N; - Line_Group_ID: 0 has been set to: CMARS_OUTOFSERVICE
139234mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=B CMCSOverlapRecv->CMCSAccept
139234mS CMCallEvt: 0.1002.0 1 Extn204.0: StateChange: END=A CMCSDialling->CMCSRingBack
139235mS CMExtnEvt: v=4 State, new=Alerting old=Dialling,0,0,Extn204
139235mS CMExtnTx: v=204, p1=0
CMProgress
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [WAITING_FOR_LINE]
Timed: 10/06/10 16:05
139235mS CD: CALL: 0.1002.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum=913107774500 () CallingNum=204 (Extn204) Internal=1 Time=13135 AState=Ringing
139236mS ISDNL1Tx: v=1 peb=1
0000 00 01 7f ...
139236mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
139237mS CMMap: a=21.39 b=1.255 T
139237mS CMMap: PCG::MapBChan pcp[122]b1r0 cp_b f56246c0 other_cp_b f5a7809c type CGTypeDTMF
139237mS CMMap: a=21.39 b=20.4 M2
139237mS CMMap: DTMF:
layTones g T[15] ,EEEEE139237mS CMMap: Started playing tones, cp[122]b1r0 (from g null)
139237mS CMMap: cp[122]b1r0 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
140231mS ISDNL2Tx: v=1 peb=1
0000 00 01 7f ...
140231mS ISDNL1Tx: v=1 peb=1
0000 00 01 7f ...
140234mS CMARS: Retarget existing BUSY targets - Num of Busy Targets: 1 - Current Form: Main
140257mS RES: Thu 10/6/2010 16:05:46 FreeMem=70101608(2) CMMsg=4 (5) Buff=200 952 1000 7374 5 Links=3613
140257mS RES2: IP 500 V2 6.0(8) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
141058mS CMMap: PCG::UnmapBChan pcp[122]b1r0 cp_b f56246c0 other_cp_b f5a7809c
141058mS CMMap: a=21.39 b=20.4 M0
141058mS CMMap: a=21.39 b=0.0 T0
141058mS CMMap: a=21.39 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
141059mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
141059mS CMMap: a=21.39 b=1.255 T
141059mS CMMap: PCG::MapBChan pcp[122]b1r0 cp_b f56246c0 other_cp_b f5a7809c type CGTypeDTMF
141059mS CMMap: a=21.39 b=20.4 M2
141059mS CMMap: DTMF:
layTones g T[16] ,EEEEE141059mS CMMap: Started playing tones, cp[122]b1r0 (from g null)
141060mS CMMap: cp[122]b1r0 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
141231mS ISDNL2Tx: v=1 peb=1
0000 00 01 7f ...
141231mS ISDNL1Tx: v=1 peb=1
0000 00 01 7f ...
142231mS ISDNL2Tx: v=1 peb=1
0000 00 01 7f ...
142231mS ISDNL1Tx: v=1 peb=1
0000 00 01 7f ...
142880mS CMMap: PCG::UnmapBChan pcp[122]b1r0 cp_b f56246c0 other_cp_b f5a7809c
142880mS CMMap: a=21.39 b=20.4 M0
142880mS CMMap: a=21.39 b=0.0 T0
142880mS CMMap: a=21.39 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
143231mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
143771mS ISDNL1Evt: v=1 peb=1,Locked
143771mS PRN: Falc: LockToFalc: 1
143771mS PRN: MEZZ_PRI_CLOCK_CNTL: f1
143771mS PRN: FPGA_REC_CLK_SELECT: 08
143771mS PRN: SetSlaveMode: 01
146173mS CMExtnRx: v=204, p1=0
CMReleaseComp
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
146173mS CMCallEvt: 0.1002.0 1 Extn204.0: StateChange: END=A CMCSRingBack->CMCSCompleted
146174mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Alerting,0,0,Extn204
146175mS CMLOGGING: CALL:2010/06/1016:05,00:00:00,000,204,O,913107774500,913107774500,Extn204,,,1,,""n/a,0
146175mS CD: CALL: 0.1002.0 BState=Ringing Cut=2 Music=0.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum=913107774500 () CallingNum=204 (Extn204) Internal=1 Time=20075 AState=Idle
146175mS CD: CALL: 0.1002.0 Deleted
146175mS CMExtnEvt: Extn204: CALL LOST (CMCauseNormal)
146176mS CMExtnEvt: Extn204: Extn(204) Calling Party Number(204) Type(CMNTypeInternal)
146176mS CMExtnEvt: Extn204: CMExtnHandler::SetCurrent( id: 1002->0 )
146176mS CMCallEvt: 0.1002.0 -1 Extn204.-1: StateChange: END=X CMCSCompleted->CMCSDelete
146176mS CMARS: CMARSEndpoint::CallLost(cause=16) - Address: 0.1004.0 1 ARS for Main - Call State: CMCSAccept
146177mS CMCallEvt: 0.1004.0 -1 ARS for Main: StateChange: END=X CMCSAccept->CMCSDelete
146177mS CMCallEvt: 0.1004.0 -1 BaseEP: DELETE CMEndpoint f55a946c TOTAL NOW=2 CALL_LIST=0
146177mS CMCallEvt: END CALL:1 (f55b35ac)
146178mS CMTARGET: 0.1002.0 -1 BaseEP: ~CMTargetHandler f55acae4 ep f55b3a08
146178mS CMCallEvt: 0.1002.0 -1 BaseEP: DELETE CMEndpoint f55b3a08 TOTAL NOW=1 CALL_LIST=0
146258mS RES: Thu 10/6/2010 16:05:52 FreeMem=70129572(2) CMMsg=4 (5) Buff=200 952 1000 7376 5 Links=3624
146258mS RES2: IP 500 V2 6.0(8) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=44 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
147336mS CMCallEvt: 0.1006.0 -1 BaseEP: NEW CMEndpoint f55b3a08 TOTAL NOW=2 CALL_LIST=0
147336mS CMCallEvt: 0.1006.0 -1 Extn204.-1: NEW CMExtnEndpoint f55b3a08, Name=Extn204, Extn=204, Phys Extn=204
147336mS CMCallEvt: CREATE CALL:2 (f55b35ac)
147336mS CMCallEvt: 0.1007.0 -1 BaseEP: NEW CMEndpoint f55b2198 TOTAL NOW=3 CALL_LIST=0
147338mS CMExtnEvt: Extn204: CMExtnHandler::SetCurrent( id: 0->1006 )
147338mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Extn204
147338mS CMExtnRx: v=204, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=1006 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[204] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn204
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Extn204
147339mS CMCallEvt: 0.1006.0 2 Extn204.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
147339mS CMExtnEvt: v=4 State, new=Connected old=Idle,0,0,Extn204
147339mS CMTARGET: 0.1006.0 2 Extn204.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=204 dir=out complete=0 ses=0
147339mS CMTARGET: 0.1006.0 2 Extn204.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
147340mS CMCallEvt: 0.1006.0 2 Extn204.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
147340mS CMExtnEvt: v=4 State, new=Dialling old=Connected,0,0,Extn204
147340mS CMExtnTx: v=204, p1=0
CMSetupAck
Line: type=DigitalExtn 3 Call: lid=0 id=1006 in=0
BChan: slot=20 chan=4
Timed: 10/06/10 16:05
147341mS CD: CALL: 0.1006.0 BState=Idle Cut=1 Music=3.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum= () CallingNum=204 (Extn204) Internal=1 Time=5 AState=DialInitiated
147342mS CMMap: a=20.4 b=0.0 D1
150035mS CMExtnRx: v=204, p1=0
CMReleaseComp
Line: type=DigitalExtn 3 Call: lid=0 id=1006 in=0
150035mS CMCallEvt: 0.1006.0 2 Extn204.0: StateChange: END=A CMCSDialling->CMCSCompleted
150036mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Dialling,0,0,Extn204
150036mS CMLOGGING: CALL:2010/06/1016:05,00:00:00,000,204,O,,,Extn204,,,1,,""n/a,0
150037mS CD: CALL: 0.1006.0 BState=Idle Cut=0 Music=0.0 Aend="Extn204(204)" (20.4) Bend="" [] (0.0) CalledNum= () CallingNum=204 (Extn204) Internal=1 Time=2701 AState=Idle
150037mS CD: CALL: 0.1006.0 Deleted
150037mS CMExtnEvt: Extn204: CALL LOST (CMCauseNormal)
150037mS CMExtnEvt: Extn204: Extn(204) Calling Party Number(204) Type(CMNTypeInternal)
150038mS CMExtnEvt: Extn204: CMExtnHandler::SetCurrent( id: 1006->0 )
150038mS CMCallEvt: 0.1006.0 -1 Extn204.-1: StateChange: END=X CMCSCompleted->CMCSDelete
150038mS CMCallEvt: 0.1007.0 -1 BaseEP: DELETE CMEndpoint f55b2198 TOTAL NOW=2 CALL_LIST=0
150038mS CMCallEvt: END CALL:2 (f55b35ac)
150039mS CMTARGET: 0.1006.0 -1 BaseEP: ~CMTargetHandler f55acae4 ep f55b3a08
150039mS CMCallEvt: 0.1006.0 -1 BaseEP: DELETE CMEndpoint f55b3a08 TOTAL NOW=1 CALL_LIST=0
150040mS CMMap: a=20.4 b=0.0 D0
152038mS CMExtnEvt: Extn204: Recover Timer reason=CMTRWrapUp
152038mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Extn204
152039mS CMExtnTx: v=204, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Extn204 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn204 Msgs=0]
Timed: 10/06/10 16:05
********** Warning: Logging to Screen Stopped **********
