ValiantSoft1
IS-IT--Management
Installed IP office V500 v8.0.18 in partner mode with combo card and 1416 phones. Caller ID does not work; phone display just reads "External" on all incoming calls. User had an Avaya Partner V7 before and caller was working. Lines are set to loop start with ICLID and Long CLI option is checked. Here is a log of an incoming call. The calling number and name have been replaced with ZZZZZZZZZ and ZZZ COMP NAME. Thank you in advance
********** Warning: Logging to Screen Started **********
1184526mS ATMChannel: [1] CLI Message Rx:
1184526mS ATMChannel: 0x3036
1184526mS ATMChannel: 0x3139
1184526mS ATMChannel: 0x3137
1184526mS ATMChannel: 0x3135
1184526mS ATMChannel: end of CLI Message
1184526mS ATMChannel: [1] CLI Message Rx:
1184526mS ATMChannel: 0x5753
1184526mS ATMChannel: 0x4220
1184527mS ATMChannel: 0x434d
1184527mS ATMChannel: 0x5054
1184527mS ATMChannel: 0x5220
1184527mS ATMChannel: 0x5345
1184527mS ATMChannel: 0x5256
1184527mS ATMChannel: end of CLI Message
1184527mS ATMChannel: [1] CLI Message Rx:
1184527mS ATMChannel: 0x3731
1184527mS ATMChannel: 0x3935
1184528mS ATMChannel: 0x3839
1184528mS ATMChannel: 0x3337
1184528mS ATMChannel: 0x3633
1184528mS ATMChannel: end of CLI Message
1184528mS ATMChannel: [1] CLI Message Rx:
1184528mS ATMChannel: end of CLI Message
1184528mS ATMIO: [1] CLI DETECTION OFF, EQUALISER OFF
1184528mS ATMChannel: [1] StateChange CLIAwaitData->CLIDataSettle
1184731mS ATMChannel: [1] Sloppy timeout(200ms)
1184731mS ATMChannel: [1] StateChange CLIDataSettle->CLIAwaitSecondRing
1187211mS ATMChannel: [1] StateChange CLIAwaitSecondRing->PossibleIncoming
1187611mS ATMChannel: [1] Sloppy timeout(400ms)
1187651mS ATMChannel: [1] StateChange PossibleIncoming->Incoming
1187651mS ATMChannel: [1] CMLinkLayer Tx: 'Ringing'
1187651mS ATMCMLine: [1] CMLinkLayer Rx: 'Ringing' callid=(lid=1, id=0, in=1)
1187651mS ATMCMLine: [1] StateChange IncomingGuard->Present
1187652mS ATMCMLine: [1] IncomingCall
1187653mS CMCallEvt: 0.1011.0 -1 BaseEP: NEW CMEndpoint f529e58c TOTAL NOW=1 CALL_LIST=0
1187654mS CMCallEvt: CREATE CALL:3 (f529e05c)
1187654mS CMCallEvt: 0.1012.0 -1 BaseEP: NEW CMEndpoint f529cc18 TOTAL NOW=2 CALL_LIST=0
1187656mS CMLineRx: v=1
CMSetup
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
Called[] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[ZZZZZZZZZZ] Type=Unknown Plan=Unknown
Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=1 chan=1
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=1 channel=0
rx_gain=32 tx_gain=32 ep_callid=2 ipaddr=10.10.220.250 apps=0
Timed: 19/06/12 17:15
1187656mS CD: CALL: 1.2.1 BState=Idle Cut=1 Music=0.0 Aend="Line 1" (1.1) Bend="" [] (0.0) CalledNum= ()
CallingNum=ZZZZZZZZZZ (ZZZ COMPANY NAME) Internal=0 Time=2 AState=Idle
1187656mS CMCallEvt: 1.2.1 3 Alog Trunk:1: StateChange: END=A CMCSIdle->CMCSDialInitiated
1187657mS CMTARGET: 1.2.1 3 Alog Trunk:1: LOOKUP CALL ROUTE: type=0 called_party= sub= calling=ZZZZZZZZZZ
dir=in complete=1 ses=0
1187657mS CMTARGET: 1.2.1 3 Alog Trunk:1: SET BESTMATCH: length 0 vs -1 match= dest=
1187657mS CMCallEvt: Priority hike: call 3 priority 0->1
1187657mS CMTARGET: 1.2.1 3 Alog Trunk:1: LOOKUP ICR: DDI= CGPN=ZZZZZZZZZZ (Destination ) => CDPN=
1187657mS CMTARGET: 1.2.1 3 Alog Trunk:1: ADD TARGET (N): number= type=0 depth=1 nobar=1 setorig=1 ses=0
1187658mS CMCallEvt: 0.1013.0 -1 BaseEP: NEW CMEndpoint f5291350 TOTAL NOW=3 CALL_LIST=1
1187658mS CMCallEvt: 0.1013.0 -1 Extn11.-1: NEW CMExtnEndpoint f5291350, Name=Extn11, Extn=11, Phys Extn=11
1187658mS CMExtnEvt: Extn11: CMExtnHandler::SetCurrent( id: 0->1013 )
1187659mS CMTARGET: 0.1013.0 -1 Extn11.0: ADD COVERAGE (DefinityLineAppearance)
1187660mS CMCallEvt: 0.1014.0 -1 BaseEP: NEW CMEndpoint f5294908 TOTAL NOW=4 CALL_LIST=1
1187660mS CMCallEvt: 0.1014.0 -1 Extn10.-1: NEW CMExtnEndpoint f5294908, Name=Extn10, Extn=10, Phys Extn=10
1187660mS CMExtnEvt: Extn10: CMExtnHandler::SetCurrent( id: 0->1014 )
1187660mS CMTARGET: 0.1014.0 -1 Extn10.0: ADD COVERAGE (DefinityLineAppearance)
1187661mS CMTARGET: 1.2.1 3 Alog Trunk:1: NO INITIAL TARGETS: ??
1187661mS CMTARGET: 1.2.1 3 Alog Trunk:1: SELECT: TRY VOICEMAIL orig_hg() orig_user()
1187661mS CMTARGET: 1.2.1 3 Alog Trunk:1: Fallback() targeting failed
1187661mS CMTARGET: 1.2.1 3 Alog Trunk:1: Partner_operation: No Targets - Letting Line Appearances Alert
1187662mS CMCallEvt: 1.2.1 3 Alog Trunk:1: StateChange: END=A CMCSDialInitiated->CMCSDialled
1187662mS ATMCMLine: [1] ControlEchoCancellation ON
1187662mS ATMCMLine: [1] CMLinkLayer Tx: 'EchoControl'
1187663mS CMLineTx: v=1
CMProceeding
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
1187663mS ATMCMLine: [1] StateChange Present->ICProceeding
1187663mS CMCallEvt: 0.1012.0 3 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
1187664mS CMCallEvt: 0.1013.0 3 Extn11.0: StateChange: END=T CMCSIdle->CMCSOffering
1187664mS CMExtnTx: v=11, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1013 in=0
Called[] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[ZZZZZZZZZZ] Type=Unknown Plan=Unknown
Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=1 channel=0
rx_gain=32 tx_gain=32 ep_callid=2 ipaddr=10.10.220.250 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:0 R:4) number=ZZZZZZZZZZ
Display [ZZZ COMPANY NAME>]
Timed: 19/06/12 17:15
Locale: enu
1187665mS CMExtnRx: v=11, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1013 in=0
1187665mS CMCallEvt: 0.1013.0 3 Extn11.0: StateChange: END=T CMCSOffering->CMCSRinging
1187665mS CMExtnEvt: v=2 State, new=Ringing old=Idle,0,0,Extn11
1187666mS CMCallEvt: 0.1012.0 3 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
1187666mS CMCallEvt: 1.2.1 3 Alog Trunk:1: StateChange: END=A CMCSDialled->CMCSRingBack
1187667mS CMLineTx: v=1
CMAlerting
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
1187667mS ATMCMLine: [1] StateChange ICProceeding->Received
1187668mS CMCallEvt: 0.1014.0 3 Extn10.0: StateChange: END=T CMCSIdle->CMCSOffering
1187668mS CMExtnTx: v=10, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1014 in=0
Called[] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[ZZZZZZZZZZ] Type=Unknown Plan=Unknown
Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:0 R:4) number=ZZZZZZZZZZ
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=1 channel=0
rx_gain=32 tx_gain=32 ep_callid=2 ipaddr=10.10.220.250 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
Display [ZZZ COMPANY NAME>]
Timed: 19/06/12 17:15
Locale: enu
1187668mS CMExtnRx: v=10, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1014 in=0
1187669mS CMCallEvt: 0.1014.0 3 Extn10.0: StateChange: END=T CMCSOffering->CMCSRinging
1187669mS CMExtnEvt: v=1 State, new=Ringing old=Idle,0,0,Extn10
1187670mS CD: CALL: 1.2.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.1) Bend="" [Extn11(11),Extn10(10)]
(0.0) CalledNum= () CallingNum=ZZZZZZZZZZ (ZZZ COMPANY NAME) Internal=0 Time=16 AState=Ringing
1187671mS CMMap: a=1.1 b=0.0 R1
1187672mS ATMChannel: [1] CMLinkLayer Rx: 'EchoControl' (ls)
1187672mS ATMIO: [1] ECHO CANCELLATION ON
1187887mS RES: Tue 19/6/2012 17:15:27 FreeMem=62339456(2) CMMsg=4 (5) Buff=5200 946 1000 7463 5 Links=8496
1187887mS RES2: (BEP) IP 500 V2 8.0(18) Tasks=40 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0
CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1207662mS CMTARGET: 1.2.1 3 Alog Trunk:1: TimerExpired cause=CMTCNoAnswerTimeout
1207662mS CMTARGET: 1.2.1 3 Alog Trunk:1: ADD VM TARGET
1207662mS CMTARGET: **** 1.2.1 3 Alog Trunk:1: MakeVoicemailTarget pbx=<null> local=1 type=3
1207663mS CMCallEvt: 0.1015.0 -1 BaseEP: NEW CMEndpoint f528ef2c TOTAL NOW=5 CALL_LIST=1
1207663mS CMTARGET: 0.1015.0 3 RAS.0: ADD PRIMARY
1207663mS CMTARGET: 1.2.1 3 Alog Trunk:1: ADD VM TARGET: SUCCEEDED
1207664mS CMExtnEvt: Extn11: CALL LOST (CMCauseRetargetNoAnswer)
1207664mS CMExtnEvt: Extn11: Extn(11) Calling Party Number(ZZZZZZZZZZ) Type(CMNTypeUnknown)
1207664mS CMCallEvt: 0.1013.0 -1 Extn11.0: StateChange: END=X CMCSRinging->CMCSCompleted
1207665mS CMExtnEvt: v=2 State, new=PortRecoverDelay old=Ringing,0,0,Extn11
1207665mS CMExtnTx: v=11, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1013 in=0
Called[] Type=Unknown (0) Reason=CMDRdirect Calling[ZZZZZZZZZZ] Type=Unknown Plan=Unknown Pres=Allowed
(0)
Cause=129, Retarget No Answer(IPO)
Timed: 19/06/12 17:15
1207665mS CMExtnEvt: Extn11: CMExtnHandler::SetCurrent( id: 1013->0 )
1207666mS CMCallEvt: 0.1013.0 -1 Extn11.-1: StateChange: END=X CMCSCompleted->CMCSDelete
1207666mS CMCallEvt: 0.1013.0 -1 BaseEP: DELETE CMEndpoint f5291350 TOTAL NOW=4 CALL_LIST=1
1207667mS CMExtnEvt: Extn10: CALL LOST (CMCauseRetargetNoAnswer)
1207667mS CMExtnEvt: Extn10: Extn(10) Calling Party Number(ZZZZZZZZZZ) Type(CMNTypeUnknown)
1207667mS CMCallEvt: 0.1014.0 -1 Extn10.0: StateChange: END=X CMCSRinging->CMCSCompleted
1207667mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Ringing,0,0,Extn10
1207668mS CMExtnTx: v=10, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1014 in=0
Called[] Type=Unknown (0) Reason=CMDRdirect Calling[ZZZZZZZZZZ] Type=Unknown Plan=Unknown Pres=Allowed
(0)
Cause=129, Retarget No Answer(IPO)
Timed: 19/06/12 17:15
1207668mS CMExtnEvt: Extn10: CMExtnHandler::SetCurrent( id: 1014->0 )
1207668mS CMCallEvt: 0.1014.0 -1 Extn10.-1: StateChange: END=X CMCSCompleted->CMCSDelete
1207669mS CMCallEvt: 0.1014.0 -1 BaseEP: DELETE CMEndpoint f5294908 TOTAL NOW=3 CALL_LIST=1
1207669mS CMTARGET: 1.2.1 3 Alog Trunk:1: (Partner mode) GetNoAnswerTimer returned 0: NOT STARTING
1207669mS CMCallEvt: 0.1015.0 3 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
1207670mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1015 )
1207670mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#AAEAA1] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[ZZZZZZZZZZ] Type=Unknown
Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:0 R:4) number=ZZZZZZZZZZ
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=1 channel=0
rx_gain=32 tx_gain=32 ep_callid=2 ipaddr=10.10.220.250 apps=0
Display [ZZZ COMPANY NAME>#AAEAA1]
Timed: 19/06/12 17:15
Locale: enu
1207670mS CMMap: a=21.42 b=1.254 T
1207673mS CD: CALL: 1.2.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.1) Bend="" [RAS] (0.0)
CalledNum=#AAEAA1 () CallingNum=ZZZZZZZZZZ (ZZZ COMPANY NAME) Internal=0 Time=20019 AState=Ringing
1207674mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=RAS 1 Call: lid=0 id=1011 in=0
1207674mS CMCallEvt: 0.1015.0 3 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
1207674mS CMCallEvt: 0.1012.0 3 TargetingEP: RequestEnd 0.1015.0 3 RAS.0
1207675mS CMCallEvt: 0.1012.0 -1 BaseEP: DELETE CMEndpoint f529cc18 TOTAL NOW=2 CALL_LIST=1
1207675mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
1207675mS CMCallEvt: 1.2.1 3 Alog Trunk:1: StateChange: END=A CMCSRingBack->CMCSOGConnReq
1207676mS ATMCMLine: [1] ControlGainAdjust 2
1207676mS ATMCMLine: [1] CMLinkLayer Tx: 'IncrementGain'
1207676mS CMLineTx: v=1
CMConnect
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#AAEAA1
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#AAEAA1
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42
rx_gain=32 tx_gain=32 ep_callid=1015 ipaddr=10.10.220.250 apps=0
1207676mS CMLineRx: v=1
CMConnectAck
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
BChan: slot=1 chan=1
1207677mS CMCallEvt: 1.2.1 3 Alog Trunk:1: StateChange: END=A CMCSOGConnReq->CMCSConnected
1207677mS CMTARGET: 1.2.1 3 Alog Trunk:1: ~CMTargetHandler f5297024 ep f529e58c
1207677mS CMCallEvt: 0.1015.0 3 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
1207678mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
1207681mS CMMap: a=1.1 b=0.0 R0
1207681mS CMMap: PCG::MapBChan pcp[67]b1r0 cp_b f59854bc other_cp_b 0 type CGTypeSimple
1207681mS CMMap: PCG::MapBChan pcp[22]b1r0 cp_b f59a0408 other_cp_b f59854bc type CGTypeSimple
1207681mS CMMap: a=1.1 b=21.42 M1
1207681mS CMMap: a=1.1 b=21.42 CheckSpeechPathMonitor (pcp[22]b1r0) register
1207682mS PRN: Confer DSP, channel 0: allocated DTMF detector 0, 23 free
1207682mS PRN: Confer DSP: enabled DTMF detector 0
1207684mS ATMChannel: [1] CMLinkLayer Rx: 'IncrementGain' (ls)
1207723mS ATMCMLine: [1] StateChange Received->ConnReq
1207723mS ATMCMLine: [1] CMLinkLayer Tx: 'Answer'
1207724mS CMExtnEvt: Extn11: Recover Timer reason=CMTRWrapUp
1207724mS CMExtnEvt: v=2 State, new=Idle old=PortRecoverDelay,0,0,Extn11
1207724mS CMExtnEvt: Extn10: Recover Timer reason=CMTRWrapUp
1207724mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Extn10
1207725mS CD: CALL: 1.2.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (1.1) Bend="#AAEAA1(#AAEAA1)"
[VoiceMail] (21.42) CalledNum=#AAEAA1 () CallingNum=ZZZZZZZZZZ (ZZZ COMPANY NAME) Internal=0 Time=20071
AState=Connected
1207726mS ATMChannel: [1] CMLinkLayer Rx: 'Answer' (ls)
1207726mS ATMIO: [1] BLOCK FORWARDING OFF
1207727mS ATMIO: [1] TRUNK SEIZE
1207727mS ATMChannel: [1] CMLinkLayer Tx: 'AnswerAck'
1207727mS ATMChannel: [1] StateChange Incoming->Connected
1207727mS ATMIO: [1] CLI DETECTION OFF, EQUALISER OFF
1207727mS ATMIO: [1] BLOCK FORWARDING ON
1207728mS ATMCMLine: [1] CMLinkLayer Rx: 'AnswerAck' callid=(lid=1, id=2, in=1)
1207728mS ATMCMLine: [1] StateChange ConnReq->Active
1207728mS ATMCMLine: [1] CM Tx: 'CMConnectAck' callid=(lid=1, id=2, in=1)
1207729mS CMLineRx: v=1
CMConnectAck
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
BChan: slot=1 chan=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:0 R:4) number=ZZZZZZZZZZ
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=1 channel=0
rx_gain=32 tx_gain=32 ep_callid=2 ipaddr=10.10.220.250 apps=0
1207736mS ATMChannel: [1] External Rx: 'BackToSeized' (ls)
1207887mS RES: Tue 19/6/2012 17:15:47 FreeMem=62332032(2) CMMsg=4 (5) Buff=5200 926 1000 7462 5 Links=8465
1207887mS RES2: (BEP) IP 500 V2 8.0(18) Tasks=40 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0
CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1208227mS ATMChannel: [1] Sloppy timeout(500ms)
1208227mS ATMIO: [1] BLOCK FORWARDING OFF
********** Warning: Logging to Screen Stopped **********
********** Warning: Logging to Screen Started **********
1184526mS ATMChannel: [1] CLI Message Rx:
1184526mS ATMChannel: 0x3036
1184526mS ATMChannel: 0x3139
1184526mS ATMChannel: 0x3137
1184526mS ATMChannel: 0x3135
1184526mS ATMChannel: end of CLI Message
1184526mS ATMChannel: [1] CLI Message Rx:
1184526mS ATMChannel: 0x5753
1184526mS ATMChannel: 0x4220
1184527mS ATMChannel: 0x434d
1184527mS ATMChannel: 0x5054
1184527mS ATMChannel: 0x5220
1184527mS ATMChannel: 0x5345
1184527mS ATMChannel: 0x5256
1184527mS ATMChannel: end of CLI Message
1184527mS ATMChannel: [1] CLI Message Rx:
1184527mS ATMChannel: 0x3731
1184527mS ATMChannel: 0x3935
1184528mS ATMChannel: 0x3839
1184528mS ATMChannel: 0x3337
1184528mS ATMChannel: 0x3633
1184528mS ATMChannel: end of CLI Message
1184528mS ATMChannel: [1] CLI Message Rx:
1184528mS ATMChannel: end of CLI Message
1184528mS ATMIO: [1] CLI DETECTION OFF, EQUALISER OFF
1184528mS ATMChannel: [1] StateChange CLIAwaitData->CLIDataSettle
1184731mS ATMChannel: [1] Sloppy timeout(200ms)
1184731mS ATMChannel: [1] StateChange CLIDataSettle->CLIAwaitSecondRing
1187211mS ATMChannel: [1] StateChange CLIAwaitSecondRing->PossibleIncoming
1187611mS ATMChannel: [1] Sloppy timeout(400ms)
1187651mS ATMChannel: [1] StateChange PossibleIncoming->Incoming
1187651mS ATMChannel: [1] CMLinkLayer Tx: 'Ringing'
1187651mS ATMCMLine: [1] CMLinkLayer Rx: 'Ringing' callid=(lid=1, id=0, in=1)
1187651mS ATMCMLine: [1] StateChange IncomingGuard->Present
1187652mS ATMCMLine: [1] IncomingCall
1187653mS CMCallEvt: 0.1011.0 -1 BaseEP: NEW CMEndpoint f529e58c TOTAL NOW=1 CALL_LIST=0
1187654mS CMCallEvt: CREATE CALL:3 (f529e05c)
1187654mS CMCallEvt: 0.1012.0 -1 BaseEP: NEW CMEndpoint f529cc18 TOTAL NOW=2 CALL_LIST=0
1187656mS CMLineRx: v=1
CMSetup
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
Called[] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[ZZZZZZZZZZ] Type=Unknown Plan=Unknown
Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=1 chan=1
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=1 channel=0
rx_gain=32 tx_gain=32 ep_callid=2 ipaddr=10.10.220.250 apps=0
Timed: 19/06/12 17:15
1187656mS CD: CALL: 1.2.1 BState=Idle Cut=1 Music=0.0 Aend="Line 1" (1.1) Bend="" [] (0.0) CalledNum= ()
CallingNum=ZZZZZZZZZZ (ZZZ COMPANY NAME) Internal=0 Time=2 AState=Idle
1187656mS CMCallEvt: 1.2.1 3 Alog Trunk:1: StateChange: END=A CMCSIdle->CMCSDialInitiated
1187657mS CMTARGET: 1.2.1 3 Alog Trunk:1: LOOKUP CALL ROUTE: type=0 called_party= sub= calling=ZZZZZZZZZZ
dir=in complete=1 ses=0
1187657mS CMTARGET: 1.2.1 3 Alog Trunk:1: SET BESTMATCH: length 0 vs -1 match= dest=
1187657mS CMCallEvt: Priority hike: call 3 priority 0->1
1187657mS CMTARGET: 1.2.1 3 Alog Trunk:1: LOOKUP ICR: DDI= CGPN=ZZZZZZZZZZ (Destination ) => CDPN=
1187657mS CMTARGET: 1.2.1 3 Alog Trunk:1: ADD TARGET (N): number= type=0 depth=1 nobar=1 setorig=1 ses=0
1187658mS CMCallEvt: 0.1013.0 -1 BaseEP: NEW CMEndpoint f5291350 TOTAL NOW=3 CALL_LIST=1
1187658mS CMCallEvt: 0.1013.0 -1 Extn11.-1: NEW CMExtnEndpoint f5291350, Name=Extn11, Extn=11, Phys Extn=11
1187658mS CMExtnEvt: Extn11: CMExtnHandler::SetCurrent( id: 0->1013 )
1187659mS CMTARGET: 0.1013.0 -1 Extn11.0: ADD COVERAGE (DefinityLineAppearance)
1187660mS CMCallEvt: 0.1014.0 -1 BaseEP: NEW CMEndpoint f5294908 TOTAL NOW=4 CALL_LIST=1
1187660mS CMCallEvt: 0.1014.0 -1 Extn10.-1: NEW CMExtnEndpoint f5294908, Name=Extn10, Extn=10, Phys Extn=10
1187660mS CMExtnEvt: Extn10: CMExtnHandler::SetCurrent( id: 0->1014 )
1187660mS CMTARGET: 0.1014.0 -1 Extn10.0: ADD COVERAGE (DefinityLineAppearance)
1187661mS CMTARGET: 1.2.1 3 Alog Trunk:1: NO INITIAL TARGETS: ??
1187661mS CMTARGET: 1.2.1 3 Alog Trunk:1: SELECT: TRY VOICEMAIL orig_hg() orig_user()
1187661mS CMTARGET: 1.2.1 3 Alog Trunk:1: Fallback() targeting failed
1187661mS CMTARGET: 1.2.1 3 Alog Trunk:1: Partner_operation: No Targets - Letting Line Appearances Alert
1187662mS CMCallEvt: 1.2.1 3 Alog Trunk:1: StateChange: END=A CMCSDialInitiated->CMCSDialled
1187662mS ATMCMLine: [1] ControlEchoCancellation ON
1187662mS ATMCMLine: [1] CMLinkLayer Tx: 'EchoControl'
1187663mS CMLineTx: v=1
CMProceeding
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
1187663mS ATMCMLine: [1] StateChange Present->ICProceeding
1187663mS CMCallEvt: 0.1012.0 3 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
1187664mS CMCallEvt: 0.1013.0 3 Extn11.0: StateChange: END=T CMCSIdle->CMCSOffering
1187664mS CMExtnTx: v=11, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1013 in=0
Called[] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[ZZZZZZZZZZ] Type=Unknown Plan=Unknown
Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=1 channel=0
rx_gain=32 tx_gain=32 ep_callid=2 ipaddr=10.10.220.250 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:0 R:4) number=ZZZZZZZZZZ
Display [ZZZ COMPANY NAME>]
Timed: 19/06/12 17:15
Locale: enu
1187665mS CMExtnRx: v=11, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1013 in=0
1187665mS CMCallEvt: 0.1013.0 3 Extn11.0: StateChange: END=T CMCSOffering->CMCSRinging
1187665mS CMExtnEvt: v=2 State, new=Ringing old=Idle,0,0,Extn11
1187666mS CMCallEvt: 0.1012.0 3 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
1187666mS CMCallEvt: 1.2.1 3 Alog Trunk:1: StateChange: END=A CMCSDialled->CMCSRingBack
1187667mS CMLineTx: v=1
CMAlerting
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
1187667mS ATMCMLine: [1] StateChange ICProceeding->Received
1187668mS CMCallEvt: 0.1014.0 3 Extn10.0: StateChange: END=T CMCSIdle->CMCSOffering
1187668mS CMExtnTx: v=10, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1014 in=0
Called[] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[ZZZZZZZZZZ] Type=Unknown Plan=Unknown
Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:0 R:4) number=ZZZZZZZZZZ
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=1 channel=0
rx_gain=32 tx_gain=32 ep_callid=2 ipaddr=10.10.220.250 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
Display [ZZZ COMPANY NAME>]
Timed: 19/06/12 17:15
Locale: enu
1187668mS CMExtnRx: v=10, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1014 in=0
1187669mS CMCallEvt: 0.1014.0 3 Extn10.0: StateChange: END=T CMCSOffering->CMCSRinging
1187669mS CMExtnEvt: v=1 State, new=Ringing old=Idle,0,0,Extn10
1187670mS CD: CALL: 1.2.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.1) Bend="" [Extn11(11),Extn10(10)]
(0.0) CalledNum= () CallingNum=ZZZZZZZZZZ (ZZZ COMPANY NAME) Internal=0 Time=16 AState=Ringing
1187671mS CMMap: a=1.1 b=0.0 R1
1187672mS ATMChannel: [1] CMLinkLayer Rx: 'EchoControl' (ls)
1187672mS ATMIO: [1] ECHO CANCELLATION ON
1187887mS RES: Tue 19/6/2012 17:15:27 FreeMem=62339456(2) CMMsg=4 (5) Buff=5200 946 1000 7463 5 Links=8496
1187887mS RES2: (BEP) IP 500 V2 8.0(18) Tasks=40 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0
CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1207662mS CMTARGET: 1.2.1 3 Alog Trunk:1: TimerExpired cause=CMTCNoAnswerTimeout
1207662mS CMTARGET: 1.2.1 3 Alog Trunk:1: ADD VM TARGET
1207662mS CMTARGET: **** 1.2.1 3 Alog Trunk:1: MakeVoicemailTarget pbx=<null> local=1 type=3
1207663mS CMCallEvt: 0.1015.0 -1 BaseEP: NEW CMEndpoint f528ef2c TOTAL NOW=5 CALL_LIST=1
1207663mS CMTARGET: 0.1015.0 3 RAS.0: ADD PRIMARY
1207663mS CMTARGET: 1.2.1 3 Alog Trunk:1: ADD VM TARGET: SUCCEEDED
1207664mS CMExtnEvt: Extn11: CALL LOST (CMCauseRetargetNoAnswer)
1207664mS CMExtnEvt: Extn11: Extn(11) Calling Party Number(ZZZZZZZZZZ) Type(CMNTypeUnknown)
1207664mS CMCallEvt: 0.1013.0 -1 Extn11.0: StateChange: END=X CMCSRinging->CMCSCompleted
1207665mS CMExtnEvt: v=2 State, new=PortRecoverDelay old=Ringing,0,0,Extn11
1207665mS CMExtnTx: v=11, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1013 in=0
Called[] Type=Unknown (0) Reason=CMDRdirect Calling[ZZZZZZZZZZ] Type=Unknown Plan=Unknown Pres=Allowed
(0)
Cause=129, Retarget No Answer(IPO)
Timed: 19/06/12 17:15
1207665mS CMExtnEvt: Extn11: CMExtnHandler::SetCurrent( id: 1013->0 )
1207666mS CMCallEvt: 0.1013.0 -1 Extn11.-1: StateChange: END=X CMCSCompleted->CMCSDelete
1207666mS CMCallEvt: 0.1013.0 -1 BaseEP: DELETE CMEndpoint f5291350 TOTAL NOW=4 CALL_LIST=1
1207667mS CMExtnEvt: Extn10: CALL LOST (CMCauseRetargetNoAnswer)
1207667mS CMExtnEvt: Extn10: Extn(10) Calling Party Number(ZZZZZZZZZZ) Type(CMNTypeUnknown)
1207667mS CMCallEvt: 0.1014.0 -1 Extn10.0: StateChange: END=X CMCSRinging->CMCSCompleted
1207667mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Ringing,0,0,Extn10
1207668mS CMExtnTx: v=10, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1014 in=0
Called[] Type=Unknown (0) Reason=CMDRdirect Calling[ZZZZZZZZZZ] Type=Unknown Plan=Unknown Pres=Allowed
(0)
Cause=129, Retarget No Answer(IPO)
Timed: 19/06/12 17:15
1207668mS CMExtnEvt: Extn10: CMExtnHandler::SetCurrent( id: 1014->0 )
1207668mS CMCallEvt: 0.1014.0 -1 Extn10.-1: StateChange: END=X CMCSCompleted->CMCSDelete
1207669mS CMCallEvt: 0.1014.0 -1 BaseEP: DELETE CMEndpoint f5294908 TOTAL NOW=3 CALL_LIST=1
1207669mS CMTARGET: 1.2.1 3 Alog Trunk:1: (Partner mode) GetNoAnswerTimer returned 0: NOT STARTING
1207669mS CMCallEvt: 0.1015.0 3 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
1207670mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1015 )
1207670mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#AAEAA1] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[ZZZZZZZZZZ] Type=Unknown
Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:0 R:4) number=ZZZZZZZZZZ
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=1 channel=0
rx_gain=32 tx_gain=32 ep_callid=2 ipaddr=10.10.220.250 apps=0
Display [ZZZ COMPANY NAME>#AAEAA1]
Timed: 19/06/12 17:15
Locale: enu
1207670mS CMMap: a=21.42 b=1.254 T
1207673mS CD: CALL: 1.2.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.1) Bend="" [RAS] (0.0)
CalledNum=#AAEAA1 () CallingNum=ZZZZZZZZZZ (ZZZ COMPANY NAME) Internal=0 Time=20019 AState=Ringing
1207674mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=RAS 1 Call: lid=0 id=1011 in=0
1207674mS CMCallEvt: 0.1015.0 3 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
1207674mS CMCallEvt: 0.1012.0 3 TargetingEP: RequestEnd 0.1015.0 3 RAS.0
1207675mS CMCallEvt: 0.1012.0 -1 BaseEP: DELETE CMEndpoint f529cc18 TOTAL NOW=2 CALL_LIST=1
1207675mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
1207675mS CMCallEvt: 1.2.1 3 Alog Trunk:1: StateChange: END=A CMCSRingBack->CMCSOGConnReq
1207676mS ATMCMLine: [1] ControlGainAdjust 2
1207676mS ATMCMLine: [1] CMLinkLayer Tx: 'IncrementGain'
1207676mS CMLineTx: v=1
CMConnect
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#AAEAA1
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#AAEAA1
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42
rx_gain=32 tx_gain=32 ep_callid=1015 ipaddr=10.10.220.250 apps=0
1207676mS CMLineRx: v=1
CMConnectAck
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
BChan: slot=1 chan=1
1207677mS CMCallEvt: 1.2.1 3 Alog Trunk:1: StateChange: END=A CMCSOGConnReq->CMCSConnected
1207677mS CMTARGET: 1.2.1 3 Alog Trunk:1: ~CMTargetHandler f5297024 ep f529e58c
1207677mS CMCallEvt: 0.1015.0 3 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
1207678mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
1207681mS CMMap: a=1.1 b=0.0 R0
1207681mS CMMap: PCG::MapBChan pcp[67]b1r0 cp_b f59854bc other_cp_b 0 type CGTypeSimple
1207681mS CMMap: PCG::MapBChan pcp[22]b1r0 cp_b f59a0408 other_cp_b f59854bc type CGTypeSimple
1207681mS CMMap: a=1.1 b=21.42 M1
1207681mS CMMap: a=1.1 b=21.42 CheckSpeechPathMonitor (pcp[22]b1r0) register
1207682mS PRN: Confer DSP, channel 0: allocated DTMF detector 0, 23 free
1207682mS PRN: Confer DSP: enabled DTMF detector 0
1207684mS ATMChannel: [1] CMLinkLayer Rx: 'IncrementGain' (ls)
1207723mS ATMCMLine: [1] StateChange Received->ConnReq
1207723mS ATMCMLine: [1] CMLinkLayer Tx: 'Answer'
1207724mS CMExtnEvt: Extn11: Recover Timer reason=CMTRWrapUp
1207724mS CMExtnEvt: v=2 State, new=Idle old=PortRecoverDelay,0,0,Extn11
1207724mS CMExtnEvt: Extn10: Recover Timer reason=CMTRWrapUp
1207724mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Extn10
1207725mS CD: CALL: 1.2.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (1.1) Bend="#AAEAA1(#AAEAA1)"
[VoiceMail] (21.42) CalledNum=#AAEAA1 () CallingNum=ZZZZZZZZZZ (ZZZ COMPANY NAME) Internal=0 Time=20071
AState=Connected
1207726mS ATMChannel: [1] CMLinkLayer Rx: 'Answer' (ls)
1207726mS ATMIO: [1] BLOCK FORWARDING OFF
1207727mS ATMIO: [1] TRUNK SEIZE
1207727mS ATMChannel: [1] CMLinkLayer Tx: 'AnswerAck'
1207727mS ATMChannel: [1] StateChange Incoming->Connected
1207727mS ATMIO: [1] CLI DETECTION OFF, EQUALISER OFF
1207727mS ATMIO: [1] BLOCK FORWARDING ON
1207728mS ATMCMLine: [1] CMLinkLayer Rx: 'AnswerAck' callid=(lid=1, id=2, in=1)
1207728mS ATMCMLine: [1] StateChange ConnReq->Active
1207728mS ATMCMLine: [1] CM Tx: 'CMConnectAck' callid=(lid=1, id=2, in=1)
1207729mS CMLineRx: v=1
CMConnectAck
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
BChan: slot=1 chan=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=ZZZ COMPANY NAME
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:0 R:4) number=ZZZZZZZZZZ
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=1 channel=0
rx_gain=32 tx_gain=32 ep_callid=2 ipaddr=10.10.220.250 apps=0
1207736mS ATMChannel: [1] External Rx: 'BackToSeized' (ls)
1207887mS RES: Tue 19/6/2012 17:15:47 FreeMem=62332032(2) CMMsg=4 (5) Buff=5200 926 1000 7462 5 Links=8465
1207887mS RES2: (BEP) IP 500 V2 8.0(18) Tasks=40 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0
CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1208227mS ATMChannel: [1] Sloppy timeout(500ms)
1208227mS ATMIO: [1] BLOCK FORWARDING OFF
********** Warning: Logging to Screen Stopped **********