Caller ID not working on new 8.0 4

[jjdad]

Hello to all:

Just recently upgraded a customer with an IPO 500v2 system to 8.0.

This system was upgraded from version 6.1 and I obtained the small user upgrade license as their are only 6 extensions in use on this system.

The problem is the Caller ID has stopped working.

It worked before the upgrade, but not after.

All 4 POTS lines are programmed for Loop Start ICLID and the phone company is providing the caller ID service.

Has anyone ran into this before?

Is it possible I have some sort of hardware mismatch with the Trunk daughter card and the new software?

Everything else seems to work fine.

Any thoughts?

THANKS!!!!

 

[NYSTECH]

Try to switch to loop start and reboot then switch back to Loop Start ICILD and reboot again

 

[montyzummer]

take upto latest R8 revision, if that fails recreate SD card, if that fails send traces to your bp,

APSS (SME)
ACSS (SME)

 

[jjdad]

I have upgraded to the lastest edition 8.0(42).

Change lines to Loop only, then rebooted, changed to Incoming ICLID, rebooted. Still no caller ID.

How do you recreate the SD card?

I may change out the Trunk card to see if there is an issue with that, but doubtful.

Any other ideas welcome.

Thanks!

 

[amriddle01]

You need to trace it with monitor and see what it says

 

[jjdad]

OK, still no good.

I have reformatted the SD card.

Recreated the SD card

Copied the config file back over.

Everything works except Caller ID.

All four line are configed for Caller ID.

The phone company is providing Caller ID, I checked with a regular phone.

Attached is a Monitor print out of an incoming call.

If anyone can pin point the problem, please let me know and MANY THANKS in advance...

********** SysMonitor v10.0 (42) **********

********** contact made with 10.1.10.250 at 18:46:09 26/4/2012 **********

********** System (10.1.10.250) has been up and running for 11mins and 41secs(701744mS) **********

********** Warning: TEXT File Logging selected **********


********** Warning: TEXT Logging to File STOPPED on 26/4/2012 18:46:09 **********
701744mS PRN: Monitor Started IP=10.1.10.12 IP 500 V2 8.0(42) Info Products
(IP Office: Supports Unicode, System Locale is enu)
701744mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, ADSL=0 VCOMP=0, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) CALLS=0(TOT=9)
701744mS PRN: Monitor Started IP=10.1.10.12 IP 500 V2 8.0(42) Info Products
(IP Office: Supports Unicode, System Locale is enu)
701744mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, ADSL=0 VCOMP=0, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) CALLS=0(TOT=9)
704744mS PRN: +++ START OF ALARM LOG DUMP +++
704744mS PRN: ALARM: 22/01/2011 12:46:11 IP 500 V2 6.1(5) <WATCHDOG> CRIT RAISED addr=00000000 d=0 pc=f00b6e30 f0006328 f0010c54 f00ec63c f020aad0 f020aa7c
704744mS PRN: ALARM: 22/05/2011 19:48:27 IP 500 V2 6.1(12) <WATCHDOG> CRIT RAISED addr=00000000 d=0 pc=f00b7a84 f000643c f001158c f00ee5f0 f020f124 f020f0d0
704744mS PRN: ALARM: 23/07/2011 14:30:05 IP 500 V2 6.1(12) <WATCHDOG> CRIT RAISED addr=00000000 d=0 pc=f00b7a88 f000643c f001158c f00ee5f0 f020f124 f020f0d0
704744mS PRN: ALARM: 24/08/2011 10:29:36 IP 500 V2 6.1(12) <WATCHDOG> CRIT RAISED addr=00000000 d=0 pc=f00b7a84 f000643c f001158c f00ee5f0 f020f124 f020f0d0
704744mS PRN: +++ END OF ALARM LOG DUMP +++
705944mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
705944mS PRN: + loader: 1.32
705944mS PRN: + cpu: id 12 board 3 pld 24 type c10 options ae02
705944mS PRN: + cpu: sn 10WZ43200200
705944mS PRN: + fpga: id 1 issue 0 build 827
705945mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
705945mS PRN: ++++++++++++++++++++ LIST OF MODULES ++++++++++++++++++++
705945mS PRN: +------------------------------------------------------------
705945mS PRN: + Slot 1: Base DIGSTA8 Board=0x13 PLD=0x06
705945mS PRN: + Mezzanine ATM4 Board=0x03 PLD=0x07
705946mS PRN: +------------------------------------------------------------
705946mS PRN: + Slot 2: Base NONE
705946mS PRN: + Mezzanine NONE
705946mS PRN: +------------------------------------------------------------
705946mS PRN: + Slot 3: Base NONE
705946mS PRN: + Mezzanine NONE
705947mS PRN: +------------------------------------------------------------
705947mS PRN: + Slot 4: Base NONE
705947mS PRN: + Mezzanine NONE
705947mS PRN: +------------------------------------------------------------
705947mS PRN: +++++++++++++++++ END OF LIST OF MODULES +++++++++++++++
705948mS PRN: +++++++++++++++++ LIST OF MEMORY CARDS ++++++++++++++++++
705948mS PRN: +------------------------------------------------------------
705948mS PRN: + System SD Card: MNTD
705948mS PRN: + CID Register: 0x02544d534430344761bff99d3200a9b3
705949mS PRN: + CSD Register: 0x400e00325b5900001d877f800a400051
705949mS PRN: + SCR Register: 0x02b5800026028302
705949mS PRN: + OCR Register: 0xc0ff8000
705950mS PRN: + Status Register: 0x000000000200000002029004005a
705951mS PRN: +------------------------------------------------------------
705951mS PRN: + Optional SD Card: UMNT
705951mS PRN: +------------------------------------------------------------
705951mS PRN: ++++++++++++++ END OF LIST OF MEMORY CARDS +++++++++++++
705951mS PRN: ++++++++++++++++++++ MEMORY CAPTURE +++++++++++++++++++++
705952mS PRN: +------------------------------------------------------------
705952mS PRN: + 0x0000000f, 0x00000000, 0x00000000, 0x0000003d, 0x00000000
705952mS PRN: + 0x00000002, 0x00000000, 0x0000003d, 0x00000000, 0x00000000
705952mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x0000003d, 0x00000000
705952mS PRN: + 0x00000001, 0x00000000, 0x00000000, 0x00000000, 0x00000000
705953mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
705953mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
705953mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
705953mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
705953mS PRN: + 0x00000000, 0x4da5d37d, 0xf38080f6, 0xe91438a5, 0x34c217a7
705954mS PRN: +------------------------------------------------------------
705954mS PRN: +++++++++++++++++ END OF MEMORY CAPTURE ++++++++++++++++
728429mS PRN: Monitor Status IP 500 V2 8.0(42)
728429mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, ADSL=0 VCOMP=0, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) CALLS=0(TOT=9)
740166mS CMCallEvt: 0.1041.0 -1 BaseEP: NEW CMEndpoint f55d16e0 TOTAL NOW=1 CALL_LIST=0
740166mS CMCallEvt: CREATE CALL:10 (f55d11f4)
740167mS CMCallEvt: 0.1042.0 -1 BaseEP: NEW CMEndpoint f55cfd30 TOTAL NOW=2 CALL_LIST=0
740169mS CMLineRx: v=1
CMSetup
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
Called[] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=1 chan=1
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.1.10.250 apps=0
740169mS CD: CALL: 1.2.1 BState=Idle Cut=1 Music=0.0 Aend="Line 1" (1.1) Bend="" [] (0.0) CalledNum= () CallingNum= () Internal=0 Time=3 AState=Idle
740169mS CMCallEvt: 1.2.1 10 Alog Trunk:1: StateChange: END=A CMCSIdle->CMCSDialInitiated
740169mS CMTARGET: 1.2.1 10 Alog Trunk:1: LOOKUP CALL ROUTE: type=0 called_party= sub= calling= dir=in complete=1 ses=0
740170mS CMTARGET: 1.2.1 10 Alog Trunk:1: SET BESTMATCH: length 0 vs -1 match= dest=Incoming
740170mS CMCallEvt: Priority hike: call 10 priority 0->1
740170mS CMTARGET: 1.2.1 10 Alog Trunk:1: LOOKUP ICR: DDI=586-1026 CGPN= (Destination Incoming ) => CDPN=Incoming
740171mS CMTARGET: 1.2.1 10 Alog Trunk:1: ADD TARGET (N): number=Incoming type=0 depth=1 nobar=1 setorig=1 ses=0
740171mS CMTARGET: 1.2.1 10 Alog Trunk:1: HG(Incoming,300,10.1.10.250) Requires Routing To Master(1). IsLocalExecutive(1)
740171mS CMTARGET: 1.2.1 10 Alog Trunk:1: HG call targeting occuring here
740171mS CMTARGET: 1.2.1 10 Alog Trunk:1: PrimeForHGTarget: Incoming setorig=1 recall=0 resetExtnVars 1
740172mS CMCallEvt: Priority hike: call 10 priority 1->5
740172mS CMTARGET: 1.2.1 10 Alog Trunk:1: AddHGTarget Incoming (depth=1) allowq=1 type=CMNTypeUnknown
740172mS CMCallEvt: 0.1043.0 -1 BaseEP: NEW CMEndpoint f55c6748 TOTAL NOW=3 CALL_LIST=1
740173mS CMTARGET: 1.2.1 10 Alog Trunk:1: INITIAL TARGETING SUCCEEDED
740173mS CMTARGET: 1.2.1 10 Alog Trunk:1: GetNoAnswerTimer:25
740173mS CMCallEvt: 1.2.1 10 Alog Trunk:1: StateChange: END=A CMCSDialInitiated->CMCSDialled
740174mS CMLineTx: v=1
CMProceeding
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
740174mS CMCallEvt: 0.1042.0 10 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
740175mS CMCallEvt: 0.1043.0 10 ACDTep(Incoming): StateChange: END=T CMCSIdle->CMCSOffering
740175mS CMTARGET: 1.2.1 10 Alog Trunk:1: CancelTimer CMTCNoAnswerTimeout
740176mS CMCallEvt: 0.1043.0 10 ACDTep(Incoming): StateChange: END=T CMCSOffering->CMCSRinging
740176mS CMCallEvt: 0.1042.0 10 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
740177mS CMCallEvt: 1.2.1 10 Alog Trunk:1: StateChange: END=A CMCSDialled->CMCSRingBack
740177mS CMLineTx: v=1
CMAlerting
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Incoming
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:100 N:100 R:4) number=300
740178mS CD: CALL: 1.2.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.1) Bend="Incoming(300)" [] (0.0) CalledNum=300 (Incoming) CallingNum= () Internal=0 Time=12 AState=Ringing
740179mS CMMap: a=1.1 b=0.0 R1
740180mS CD: CALL: 1.2.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.1) Bend="Incoming(300)" [] (0.0) CalledNum=300 (Incoming) CallingNum= () Internal=0 Time=14 AState=Ringing
740180mS CMTARGET: 1.2.1 10 Alog Trunk:1: PrimeForHGTarget: Incoming setorig=0 recall=0 resetExtnVars 0
740180mS CMTARGET: 1.2.1 10 Alog Trunk:1: AddHGTarget Incoming (depth=0) allowq=0 type=CMNTypeUnknown
740180mS CMTARGET: 1.2.1 10 Alog Trunk:1: AddHGTargetRingRotary(Sequential) Incoming ring_attempt_count 0 index 0
740181mS CMTARGET: 1.2.1 10 Alog Trunk:1: OV NOT visable:Timer running VM NOT visable:Timer running
740181mS CMTARGET: 1.2.1 10 Alog Trunk:1: ADD HG Incoming Complete search - no targets found
740283mS RES: Thu 26/4/2012 18:46:21 FreeMem=65654124(1) CMMsg=4 (5) Buff=5200 954 1000 7463 5 Links=8977
740283mS RES2: IP 500 V2 8.0(42) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=45 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=13 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
741172mS CMTARGET: 1.2.1 10 Alog Trunk:1: ActionOverflowTimeout - Call in Queue
741181mS CD: CALL: 1.2.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.1) Bend="Incoming(300)" [] (0.0) CalledNum=300 (Incoming) CallingNum= () Internal=0 Time=1015 AState=Ringing
741181mS CMTARGET: 1.2.1 10 Alog Trunk:1: PrimeForHGTarget: Incoming setorig=0 recall=0 resetExtnVars 0
741181mS CMTARGET: 1.2.1 10 Alog Trunk:1: AddHGTarget Incoming (depth=0) allowq=0 type=CMNTypeUnknown
741182mS CMTARGET: 1.2.1 10 Alog Trunk:1: OV visable. VM NOT visable:Timer running
741182mS CMTARGET: 1.2.1 10 Alog Trunk:1: FindNextInServiceHgInHgIncludedList: Main
741182mS CMTARGET: 1.2.1 10 Alog Trunk:1: PrimeForHGTarget: Main setorig=0 recall=0 resetExtnVars 1
741182mS CMTARGET: 1.2.1 10 Alog Trunk:1: AddHGTarget Main (depth=1) allowq=0 type=CMNTypeUnknown
741183mS CMTARGET: 1.2.1 10 Alog Trunk:1: AddHGTargetRingGroup Main starting at 2
741183mS CMTARGET: 1.2.1 10 Alog Trunk:1: ADD USER: Amber depth=2 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000
741184mS CMCallEvt: 0.1044.0 -1 BaseEP: NEW CMEndpoint f55af73c TOTAL NOW=4 CALL_LIST=1
741184mS CMCallEvt: 0.1044.0 -1 Amber.-1: NEW CMExtnEndpoint f55af73c, Name=Amber, Extn=12, Phys Extn=12
741185mS CMTARGET: 0.1044.0 10 Amber.0: ADD PRIMARY
741185mS CMTARGET: 1.2.1 10 Alog Trunk:1: ADD USER: Chrissy depth=2 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000
741186mS CMCallEvt: 0.1045.0 -1 BaseEP: NEW CMEndpoint f55c0acc TOTAL NOW=5 CALL_LIST=1
741186mS CMCallEvt: 0.1045.0 -1 Chrissy.-1: NEW CMExtnEndpoint f55c0acc, Name=Chrissy, Extn=13, Phys Extn=13
741187mS CMTARGET: 0.1045.0 10 Chrissy.0: ADD PRIMARY
741187mS CMTARGET: 1.2.1 10 Alog Trunk:1: ADD USER: Cordless depth=2 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000
741187mS CMCallEvt: 0.1046.0 -1 BaseEP: NEW CMEndpoint f55c3f18 TOTAL NOW=6 CALL_LIST=1
741188mS CMCallEvt: 0.1046.0 -1 Cordless.-1: NEW CMExtnEndpoint f55c3f18, Name=Cordless, Extn=15, Phys Extn=15
741188mS CMTARGET: 0.1046.0 10 Cordless.0: ADD PRIMARY
741189mS CMTARGET: 1.2.1 10 Alog Trunk:1: ADD USER: Jeff depth=2 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000
741189mS CMCallEvt: 0.1047.0 -1 BaseEP: NEW CMEndpoint f55bf2c4 TOTAL NOW=7 CALL_LIST=1
741190mS CMCallEvt: 0.1047.0 -1 Jeff.-1: NEW CMExtnEndpoint f55bf2c4, Name=Jeff, Extn=16, Phys Extn=16
741190mS CMTARGET: 0.1047.0 10 Jeff.0: ADD PRIMARY
741191mS CMTARGET: 1.2.1 10 Alog Trunk:1: Main: Not using 17: Non Tapi User not logged in And FwdU Off
741191mS CMTARGET: 1.2.1 10 Alog Trunk:1: Main: Not using 14: Non Tapi User not logged in And FwdU Off
741191mS CMTARGET: 1.2.1 10 Alog Trunk:1: ADD USER: Frank depth=2 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000
741192mS CMCallEvt: 0.1048.0 -1 BaseEP: NEW CMEndpoint f55bda58 TOTAL NOW=8 CALL_LIST=1
741192mS CMCallEvt: 0.1048.0 -1 Frank.-1: NEW CMExtnEndpoint f55bda58, Name=Frank, Extn=10, Phys Extn=10
741193mS CMTARGET: 0.1048.0 10 Frank.0: ADD PRIMARY
741193mS CMTARGET: 1.2.1 10 Alog Trunk:1: ADD USER: David depth=2 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000
741193mS CMCallEvt: 0.1049.0 -1 BaseEP: NEW CMEndpoint f55bc1a8 TOTAL NOW=9 CALL_LIST=1
741194mS CMCallEvt: 0.1049.0 -1 David.-1: NEW CMExtnEndpoint f55bc1a8, Name=David, Extn=11, Phys Extn=11
741195mS CMTARGET: 0.1049.0 10 David.0: ADD PRIMARY
741195mS CMTARGET: 1.2.1 10 Alog Trunk:1: SelectTargetAvailable 6 targets
741196mS CMCallEvt: 0.1044.0 10 Amber.0: StateChange: END=T CMCSIdle->CMCSOffering
741196mS CMExtnEvt: Amber: CMExtnHandler::SetCurrent( id: 0->1044 )
741197mS CMExtnTx: v=12, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1044 in=0
Called[12] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Incoming
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=586-1026
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
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.1.10.250 apps=0
UUI type=User2User [300..] [0x33 0x30 0x30 0xfe 0xff ]
Display [??(Incoming)>Main]
Timed: 26/04/12 18:46
Locale: enu
741197mS CMExtnRx: v=12, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1044 in=0
741197mS CMCallEvt: 0.1044.0 10 Amber.0: StateChange: END=T CMCSOffering->CMCSRinging
741197mS CMExtnEvt: v=3 State, new=Ringing old=Idle,0,0,Amber
741198mS CMCallEvt: 0.1045.0 10 Chrissy.0: StateChange: END=T CMCSIdle->CMCSOffering
741199mS CMExtnEvt: Chrissy: CMExtnHandler::SetCurrent( id: 0->1045 )
741199mS CMExtnTx: v=13, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1045 in=0
Called[13] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Incoming
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=586-1026
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
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.1.10.250 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
UUI type=User2User [300..] [0x33 0x30 0x30 0xfe 0xff ]
Display [??(Incoming)>Main]
Timed: 26/04/12 18:46
Locale: enu
741199mS CMExtnRx: v=13, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1045 in=0
741200mS CMCallEvt: 0.1045.0 10 Chrissy.0: StateChange: END=T CMCSOffering->CMCSRinging
741200mS CMExtnEvt: v=4 State, new=Ringing old=Idle,0,0,Chrissy
741201mS CMCallEvt: 0.1046.0 10 Cordless.0: StateChange: END=T CMCSIdle->CMCSOffering
741201mS CMExtnEvt: Cordless: CMExtnHandler::SetCurrent( id: 0->1046 )
741201mS CMExtnTx: v=15, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1046 in=0
Called[15] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Incoming
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=586-1026
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
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.1.10.250 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
UUI type=User2User [300..] [0x33 0x30 0x30 0xfe 0xff ]
Display [??(Incoming)>Main]
Timed: 26/04/12 18:46
Locale: enu
741202mS CMExtnRx: v=15, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1046 in=0
741202mS CMCallEvt: 0.1046.0 10 Cordless.0: StateChange: END=T CMCSOffering->CMCSRinging
741202mS CMExtnEvt: v=6 State, new=Ringing old=Idle,0,0,Cordless
741203mS CMCallEvt: 0.1047.0 10 Jeff.0: StateChange: END=T CMCSIdle->CMCSOffering
741203mS CMExtnEvt: Jeff: CMExtnHandler::SetCurrent( id: 0->1047 )
741204mS CMExtnTx: v=16, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1047 in=0
Called[16] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Incoming
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=586-1026
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
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.1.10.250 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
UUI type=User2User [300..] [0x33 0x30 0x30 0xfe 0xff ]
Display [??(Incoming)>Main]
Timed: 26/04/12 18:46
Locale: enu
741204mS CMExtnRx: v=16, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1047 in=0
741204mS CMCallEvt: 0.1047.0 10 Jeff.0: StateChange: END=T CMCSOffering->CMCSRinging
741204mS CMExtnEvt: v=7 State, new=Ringing old=Idle,0,0,Jeff
741205mS CMCallEvt: 0.1048.0 10 Frank.0: StateChange: END=T CMCSIdle->CMCSOffering
741206mS CMExtnEvt: Frank: CMExtnHandler::SetCurrent( id: 0->1048 )
741206mS CMExtnTx: v=10, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1048 in=0
Called[10] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Incoming
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=586-1026
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
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.1.10.250 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
UUI type=User2User [300..] [0x33 0x30 0x30 0xfe 0xff ]
Display [??(Incoming)>Main]
Timed: 26/04/12 18:46
Locale: enu
741206mS CMExtnRx: v=10, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1048 in=0
741207mS CMCallEvt: 0.1048.0 10 Frank.0: StateChange: END=T CMCSOffering->CMCSRinging
741207mS CMExtnEvt: v=1 State, new=Ringing old=Idle,0,0,Frank
741208mS CMCallEvt: 0.1049.0 10 David.0: StateChange: END=T CMCSIdle->CMCSOffering
741208mS CMExtnEvt: David: CMExtnHandler::SetCurrent( id: 0->1049 )
741208mS CMExtnTx: v=11, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1049 in=0
Called[11] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Incoming
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=300
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=586-1026
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
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.1.10.250 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
UUI type=User2User [300..] [0x33 0x30 0x30 0xfe 0xff ]
Display [??(Incoming)>Main]
Timed: 26/04/12 18:46
Locale: enu
741209mS CMExtnRx: v=11, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1049 in=0
741209mS CMCallEvt: 0.1049.0 10 David.0: StateChange: END=T CMCSOffering->CMCSRinging
741209mS CMExtnEvt: v=2 State, new=Ringing old=Idle,0,0,David
741210mS CMTARGET: 1.2.1 10 Alog Trunk:1: GetNoAnswerTimer:25
741211mS CD: CALL: 1.2.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.1) Bend="Incoming(300)" [Amber(12),Chrissy(13),Cordless(15),Jeff(16),...] (0.0) CalledNum=200 (Main) CallingNum= () Internal=0 Time=1045 AState=Ringing
755172mS CMTARGET: 1.2.1 10 Alog Trunk:1: ActionVoicemailTimeout
755172mS CMTARGET: 1.2.1 10 Alog Trunk:1: SELECT: TRY VOICEMAIL orig_hg(300) orig_user()
755172mS CMTARGET: 1.2.1 10 Alog Trunk:1: ADD VM TARGET
755173mS CMTARGET: **** 1.2.1 10 Alog Trunk:1: MakeVoicemailTarget pbx=<null> local=1 type=3
755173mS CMCallEvt: 0.1050.0 -1 BaseEP: NEW CMEndpoint f55ba8f8 TOTAL NOW=10 CALL_LIST=1
755174mS CMTARGET: 0.1050.0 10 RAS.0: ADD PRIMARY
755174mS CMTARGET: 1.2.1 10 Alog Trunk:1: ADD VM TARGET: SUCCEEDED
755174mS CMTARGET: 1.2.1 10 Alog Trunk:1: ActionVoicemailTimeout: Targeting VM...
755174mS CMTARGET: 1.2.1 10 Alog Trunk:1: CancelTimer CMTCNoAnswerTimeout
755175mS CMExtnEvt: Amber: CALL LOST (CMCauseRetargetNoAnswer)
755175mS CMExtnEvt: Amber: Extn(12) Calling Party Number() Type(CMNTypeUnknown)
755175mS CMCallEvt: 0.1044.0 -1 Amber.0: StateChange: END=X CMCSRinging->CMCSCompleted
755175mS CMExtnEvt: v=3 State, new=PortRecoverDelay old=Ringing,0,0,Amber
755176mS CMExtnTx: v=12, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1044 in=0
Called[12] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
Cause=129, Retarget No Answer(IPO)
Timed: 26/04/12 18:46
755176mS CMExtnEvt: Amber: CMExtnHandler::SetCurrent( id: 1044->0 )
755177mS CMCallEvt: 0.1044.0 -1 Amber.-1: StateChange: END=X CMCSCompleted->CMCSDelete
755177mS CMCallEvt: 0.1044.0 -1 BaseEP: DELETE CMEndpoint f55af73c TOTAL NOW=9 CALL_LIST=1
755178mS CMExtnEvt: Chrissy: CALL LOST (CMCauseRetargetNoAnswer)
755178mS CMExtnEvt: Chrissy: Extn(13) Calling Party Number() Type(CMNTypeUnknown)
755178mS CMCallEvt: 0.1045.0 -1 Chrissy.0: StateChange: END=X CMCSRinging->CMCSCompleted
755178mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Ringing,0,0,Chrissy
755179mS CMExtnTx: v=13, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1045 in=0
Called[13] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
Cause=129, Retarget No Answer(IPO)
Timed: 26/04/12 18:46
755179mS CMExtnEvt: Chrissy: CMExtnHandler::SetCurrent( id: 1045->0 )
755179mS CMCallEvt: 0.1045.0 -1 Chrissy.-1: StateChange: END=X CMCSCompleted->CMCSDelete
755180mS CMCallEvt: 0.1045.0 -1 BaseEP: DELETE CMEndpoint f55c0acc TOTAL NOW=8 CALL_LIST=1
755180mS CMExtnEvt: Cordless: CALL LOST (CMCauseRetargetNoAnswer)
755181mS CMExtnEvt: Cordless: Extn(15) Calling Party Number() Type(CMNTypeUnknown)
755181mS CMCallEvt: 0.1046.0 -1 Cordless.0: StateChange: END=X CMCSRinging->CMCSCompleted
755181mS CMExtnEvt: v=6 State, new=PortRecoverDelay old=Ringing,0,0,Cordless
755182mS CMExtnTx: v=15, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1046 in=0
Called[15] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
Cause=129, Retarget No Answer(IPO)
Timed: 26/04/12 18:46
755182mS CMExtnEvt: Cordless: CMExtnHandler::SetCurrent( id: 1046->0 )
755182mS CMCallEvt: 0.1046.0 -1 Cordless.-1: StateChange: END=X CMCSCompleted->CMCSDelete
755183mS CMCallEvt: 0.1046.0 -1 BaseEP: DELETE CMEndpoint f55c3f18 TOTAL NOW=7 CALL_LIST=1
755183mS CMExtnEvt: Jeff: CALL LOST (CMCauseRetargetNoAnswer)
755183mS CMExtnEvt: Jeff: Extn(16) Calling Party Number() Type(CMNTypeUnknown)
755184mS CMCallEvt: 0.1047.0 -1 Jeff.0: StateChange: END=X CMCSRinging->CMCSCompleted
755184mS CMExtnEvt: v=7 State, new=PortRecoverDelay old=Ringing,0,0,Jeff
755184mS CMExtnTx: v=16, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1047 in=0
Called[16] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
Cause=129, Retarget No Answer(IPO)
Timed: 26/04/12 18:46
755185mS CMExtnEvt: Jeff: CMExtnHandler::SetCurrent( id: 1047->0 )
755185mS CMCallEvt: 0.1047.0 -1 Jeff.-1: StateChange: END=X CMCSCompleted->CMCSDelete
755185mS CMCallEvt: 0.1047.0 -1 BaseEP: DELETE CMEndpoint f55bf2c4 TOTAL NOW=6 CALL_LIST=1
755186mS CMExtnEvt: Frank: CALL LOST (CMCauseRetargetNoAnswer)
755186mS CMExtnEvt: Frank: Extn(10) Calling Party Number() Type(CMNTypeUnknown)
755186mS CMCallEvt: 0.1048.0 -1 Frank.0: StateChange: END=X CMCSRinging->CMCSCompleted
755187mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Ringing,0,0,Frank
755187mS CMExtnTx: v=10, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1048 in=0
Called[10] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
Cause=129, Retarget No Answer(IPO)
Timed: 26/04/12 18:46
755187mS CMExtnEvt: Frank: CMExtnHandler::SetCurrent( id: 1048->0 )
755188mS CMCallEvt: 0.1048.0 -1 Frank.-1: StateChange: END=X CMCSCompleted->CMCSDelete
755188mS CMCallEvt: 0.1048.0 -1 BaseEP: DELETE CMEndpoint f55bda58 TOTAL NOW=5 CALL_LIST=1
755189mS CMExtnEvt: David: CALL LOST (CMCauseRetargetNoAnswer)
755189mS CMExtnEvt: David: Extn(11) Calling Party Number() Type(CMNTypeUnknown)
755189mS CMCallEvt: 0.1049.0 -1 David.0: StateChange: END=X CMCSRinging->CMCSCompleted
755190mS CMExtnEvt: v=2 State, new=PortRecoverDelay old=Ringing,0,0,David
755190mS CMExtnTx: v=11, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1049 in=0
Called[11] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
Cause=129, Retarget No Answer(IPO)
Timed: 26/04/12 18:46
755190mS CMExtnEvt: David: CMExtnHandler::SetCurrent( id: 1049->0 )
755191mS CMCallEvt: 0.1049.0 -1 David.-1: StateChange: END=X CMCSCompleted->CMCSDelete
755191mS CMCallEvt: 0.1049.0 -1 BaseEP: DELETE CMEndpoint f55bc1a8 TOTAL NOW=4 CALL_LIST=1
755192mS CMTARGET: 1.2.1 10 Alog Trunk:1: SelectTargetAvailable 1 targets
755192mS CMCallEvt: 0.1050.0 10 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
755193mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1050 )
755193mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#Incoming] Type=Voicemail (102) Reason=107 SndComp Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Main
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Incoming
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:3) number=300
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Incoming
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=300
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=586-1026
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
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.1.10.250 apps=0
Display [??>Main]
Timed: 26/04/12 18:46
Locale: enu
755193mS CMMap: a=21.42 b=1.254 T
755197mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=RAS 1 Call: lid=0 id=1011 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#Incoming
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
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=1050 ipaddr=10.1.10.250 apps=0
755197mS CMCallEvt: 0.1050.0 10 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
755197mS CMCallEvt: 0.1042.0 10 TargetingEP: RequestEnd 0.1050.0 10 RAS.0
755198mS CMTARGET: 1.2.1 10 Alog Trunk:1: Removed HG from Callinfo
755199mS CMCallEvt: 0.1043.0 10 ACDTep(Incoming): StateChange: END=??? CMCSRinging->CMCSDelete
755199mS CMCallEvt: 0.1043.0 -1 BaseEP: DELETE CMEndpoint f55c6748 TOTAL NOW=3 CALL_LIST=1
755199mS CMCallEvt: 0.1042.0 -1 BaseEP: DELETE CMEndpoint f55cfd30 TOTAL NOW=2 CALL_LIST=1
755200mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
755200mS CMCallEvt: 1.2.1 10 Alog Trunk:1: StateChange: END=A CMCSRingBack->CMCSOGConnReq
755201mS 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=#Incoming
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
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=1050 ipaddr=10.1.10.250 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Incoming
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=300
755201mS CMLineRx: v=1
CMConnectAck
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
BChan: slot=1 chan=1
755201mS CMCallEvt: 1.2.1 10 Alog Trunk:1: StateChange: END=A CMCSOGConnReq->CMCSConnected
755202mS CMTARGET: 1.2.1 10 Alog Trunk:1: ~CMTargetHandler f55c9f70 ep f55d16e0
755202mS CMCallEvt: 0.1050.0 10 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
755203mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
755204mS CMMap: a=1.1 b=0.0 R0
755205mS CMMap: PCG::MapBChan pcp[57]b1r0 cp_b f5ca5df0 other_cp_b 0 type CGTypeSimple
755205mS CMMap: PCG::MapBChan pcp[12]b1r0 cp_b f5cc0e9c other_cp_b f5ca5df0 type CGTypeSimple
755205mS CMMap: a=1.1 b=21.42 M1
755205mS CMMap: a=1.1 b=21.42 CheckSpeechPathMonitor (pcp[12]b1r0) register
755206mS PRN: Confer DSP, channel 0: allocated DTMF detector 0, 23 free
755206mS PRN: Confer DSP: enabled DTMF detector 0
755207mS PRN: Config Write Wake Up
755232mS CMExtnEvt: Amber: Recover Timer reason=CMTRWrapUp
755232mS CMExtnEvt: v=3 State, new=Idle old=PortRecoverDelay,0,0,Amber
755232mS CMExtnEvt: Chrissy: Recover Timer reason=CMTRWrapUp
755232mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Chrissy
755233mS CMExtnEvt: Cordless: Recover Timer reason=CMTRWrapUp
755233mS CMExtnEvt: v=6 State, new=Idle old=PortRecoverDelay,0,0,Cordless
755233mS CMExtnEvt: Jeff: Recover Timer reason=CMTRWrapUp
755233mS CMExtnEvt: v=7 State, new=Idle old=PortRecoverDelay,0,0,Jeff
755234mS CMExtnEvt: Frank: Recover Timer reason=CMTRWrapUp
755234mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Frank
755234mS CMExtnEvt: David: Recover Timer reason=CMTRWrapUp
755234mS CMExtnEvt: v=2 State, new=Idle old=PortRecoverDelay,0,0,David
755236mS CD: CALL: 1.2.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (1.1) Bend="Incoming(300)" [VoiceMail] (21.42) CalledNum=#Incoming (Main) CallingNum= () Internal=0 Time=15069 AState=Connected
755238mS CMLineRx: v=1
CMConnectAck
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
BChan: slot=1 chan=1
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
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.1.10.250 apps=0
755283mS RES: Thu 26/4/2012 18:46:36 FreeMem=65635268(1) CMMsg=4 (5) Buff=5200 945 1000 7462 5 Links=8951
755283mS RES2: IP 500 V2 8.0(42) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=56 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=13 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
755708mS PRN: Updates IO list size 0 updated list size 1
755708mS PRN: Config Write Completed
755831mS CMLineRx: v=1
CMReleaseComp
Line: type=AnalogueLine 1 Call: lid=1 id=2 in=1
BChan: slot=1 chan=1
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
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.1.10.250 apps=0
Cause=16, Normal call clearing
755832mS CMCallEvt: 1.2.1 10 Alog Trunk:1: StateChange: END=A CMCSConnected->CMCSCompleted
755833mS CMLOGGING: CALL:2012/04/2618:46,00:00:01,015,,I,200,,,,,0,,""n/a,0
755833mS CD: CALL: 1.2.1 BState=Connected Cut=2 Music=0.0 Aend="Line 1" (1.1) Bend="Incoming(300)" [VoiceMail] (21.42) CalledNum=#Incoming (Main) CallingNum= () Internal=0 Time=15667 AState=Idle
755834mS CD: CALL: 1.2.1 Deleted
755834mS CMCallEvt: 1.2.1 -1 Alog Trunk:1: StateChange: END=X CMCSCompleted->CMCSDelete
755835mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
755835mS CMCallEvt: 0.1050.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
755835mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
755836mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#Incoming] Type=Voicemail (102) Reason=107 Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BChan: slot=21 chan=42
Cause=16, Normal call clearing
755836mS CMMap: a=21.42 b=0.0 T0
755837mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 1050->0 )
755837mS CMCallEvt: 0.1050.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
755838mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
755838mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
Cause=16, Normal call clearing
755838mS CMCallEvt: 0.1050.0 -1 BaseEP: DELETE CMEndpoint f55ba8f8 TOTAL NOW=1 CALL_LIST=0
755838mS CMCallEvt: END CALL:10 (f55d11f4)
755839mS CMCallEvt: 1.2.1 -1 BaseEP: DELETE CMEndpoint f55d16e0 TOTAL NOW=0 CALL_LIST=0
755840mS CMMap: a=1.1 b=21.42 CheckSpeechPathMonitor (pcp[12]b1r0) unregister
755841mS PRN: Confer DSP: disabled DTMF detector 0
755841mS PRN: Confer DSP channel 0: freed DTMF detector 0, 24 free
755841mS CMMap: PCG::UnmapBChan pcp[12]b1r0 cp_b f5cc0e9c other_cp_b f5ca5df0
755841mS CMMap: a=1.1 b=21.42 M0
755842mS CMMap: PCG::UnmapBChan pcp[57]b1r0 cp_b f5ca5df0 other_cp_b 0
760783mS RES: Thu 26/4/2012 18:46:41 FreeMem=65688008(1) CMMsg=4 (5) Buff=5200 954 1000 7463 5 Links=8987
760783mS RES2: IP 500 V2 8.0(42) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=42 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=13 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034

 

[jjdad]

A couple updates:

I discovered that in Rev 7.0+, there is a way to select the type of Caller ID protocol the system recognizes.

It is under System/Telephony/Tones & Music

There are 3 choices for CLI type. The US supposedly uses the Bell 202 Version.

I have tried all three types and still no good.

Also, I had a standard analog phone plugged into the incoming line along with the IPO; and the analog phone rang twice with the Caller ID information being displayed before the IPO even rang once. And of course the IPO showed no Caller ID info.

Again, very frustrating situation on this.

Any suggestions or answers appreciated.

THANKS!

 

[NYSTECH]

downgrade to 8.0.18

 

[jjdad]

NYSTECH,

I will download that version and give a try tomorrow.

I am all out of ideas at this point.

Since I had to purchase an upgrade license to go from 6.1 to 8.0, If I would go back to either 7.0 or all the way back to 6.1 will I run into any problems?


THANKS!

 

[4vayaMan]

I have the same issue on 7.0.31 right now. I too have done all of the above.

 

[CarGoSki]

Perhaps system/telephony/tones and music/cli detection?

 

[Tyrrellica]

Same issue here, my distro has an open ticket with Avaya on this. I will post back when I get an update.

 

[jjdad]

Tyrrellica,

Please let me know what Avaya says about this issue, as I still have not fixed the problem from my original post.

CarGoSki:

I have tried your suggestion, but it didn't fix the problem.

Thanks!

 

[dthszn]

We are having a similar issue. Upgraded from 5 to 8 yesterday. The CID works on the PRI but not on the analog lines. We checked the caller ID ahead of the system and everything is working correctly.

 

[NYSTECH]

Did you upgrade to 8.0.43????

 

[bixtool]

We are having same issues, before we have to schedule an 8.0.43 upgrade (its a 24 hour operation) We HAVE to know Avaya fixed NOT ONLY the 5410 phone upgrade issues but also the PRI and CLID issues as well as there is considerable loss of revenue involved with the customer having to be down.

 

[dthszn]

I have not tried 8.0.43 yet. I'm looking for more information before I do that. The analog lines work, but no CLID. The PRI card is also having issues. The CO has to reboot the PRI line everytime we reboot the IPO or they do not sync up. On a positive note, the CLID works on the PRI. We changed out all of the equipment and tried different R8 versions, but still having issues. The voicemail upgrade (8.0.1009) dropped all of the modules during the upgrade. It just needs to have the call flow imported back in, but still a pain. It's the third upgrade this week that has done that.

 

[Westi]

I installed a system new on 8.0.43 on Monday and have the same issue and get no caller ID at all. I took a brand new system and tested with default configuration the caller ID worked and as soon as I popped in the proper config it stopped. I will reprogram the system from scratch and test again as it might be a configuration being corrupt.
I will try and install it tomorrow night and then report back.


Joe W.

FHandw, ACSS

 

[jjdad]

Westi:

Sounds like you are on to something.

I am still waiting on an answer from Avaya, please let us know what you find out.

THANKS!

 

[dthszn]

Just some information. After talking to Avaya, we are opening a case. We are having problems with caller ID on the analog lines and issues with the PRI.