Matt
Those were the Mitel timers. I've changed them.
Guard timer 200
interdigit 300
outpulse delay 100
wait for delay 300
This is the latest
UNICODE-UTF8
enu
5596755mS CMExtnTx: v=RAS, p1=0
CMVoiceMailMsg
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[Delete] Type=Default (100) Reason=CMDRdirect
BChan: slot=21 chan=42
Tag type=Text flags=0x1 [MSG00028.] [0x4d 0x53 0x47 0x30 0x30 0x30 0x32 0x38 0x00 ]
5596757mS CMExtnRx: v=RAS, p1=0
CMVoiceMailMsg
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[Delete] Type=Default (100) Reason=CMDRdirect
Tag type=Text flags=0x1 [MSG00028.] [0x4d 0x53 0x47 0x30 0x30 0x30 0x32 0x38 0x00 ]
5596758mS CMExtnTx: v=108, p1=0
CMVoiceMailMsg
Line: type=DigitalExtn 10 Call: lid=0 id=2385 in=0
Called[Delete] Type=Default (100) Reason=CMDRdirect
Tag type=Text flags=0x1 [MSG00028.] [0x4d 0x53 0x47 0x30 0x30 0x30 0x32 0x38 0x00 ]
Timed: 03/01/12 17:00
5596760mS CMExtnTx: v=108, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 10 Call: lid=0 id=-1 in=0
Called[Extn108 Msgs=0 Old=4 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn108 Msgs=0]
Timed: 03/01/12 17:00
5596805mS CMExtnRx: v=RAS, p1=0
CMVoiceMailMsg
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[MsgPlaying] Type=Default (100) Reason=CMDRdirect
Tag type=Text flags=0x1 [.] [0x00 ]
5596806mS CMExtnTx: v=108, p1=0
CMVoiceMailMsg
Line: type=DigitalExtn 10 Call: lid=0 id=2385 in=0
Called[MsgPlaying] Type=Default (100) Reason=CMDRdirect
Tag type=Text flags=0x1 [.] [0x00 ]
Timed: 03/01/12 17:00
********** Warning: Logging to Screen Started **********
5596934mS CMExtnRx: v=RAS, p1=0
CMVoiceMailMsg
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[Summary] Type=Default (100) Reason=CMDRdirect Calling[0,4,0] Type=Default Plan=Default
Display [Extn108]
5596934mS CMExtnTx: v=108, p1=0
CMVoiceMailMsg
Line: type=DigitalExtn 10 Call: lid=0 id=2385 in=0
Called[Summary] Type=Default (100) Reason=CMDRdirect Calling[0,4,0] Type=Default Plan=Default
Display [Extn108]
Timed: 03/01/12 17:00
5596993mS RES: Tue 3/1/2012 17:00:07 FreeMem=57223256(1) CMMsg=5 (8) Buff=5200 940 1000 7400 5 Links=24468
5596993mS RES2: IP 500 V2 8.0(16) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=68 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
5598307mS T1CAS: Channel E&M 14.5: RX: 0000 -> 1111
5598307mS T1Channel chan: E&M 14.5: Receive T1EMOffHook
5598307mS T1Channel chan: E&M 14.5: EventRx T1EMOffHook state=Idle
5598307mS T1Channel chan: E&M 14.5: StateChange Idle->IncomingHandshake
5598308mS T1DSP: PRIU DSP 4: channel 37 (timeslot 11), speech path on
5598308mS T1Line: line: 14.5: State= Idle RxMessage: Alert_PreIndication
5598309mS T1Line: line: 14.5: State Change Idle -> IncomingGuard
5598327mS T1Channel chan: E&M 14.5: StateChange IncomingHandshake->IncomingAlerting
5598427mS T1Channel chan: E&M 14.5: Transmit T1EMOffHook
5598427mS T1CAS: Channel E&M 14.5: TX: 0000 -> 1111
5598427mS T1Channel chan: E&M 14.5: StateChange IncomingAlerting->WinkSending
5598551mS CMExtnRx: v=108, p1=0
CMReleaseComp
Line: type=DigitalExtn 10 Call: lid=0 id=2385 in=0
5598551mS CMCallEvt: 0.2385.0 403 Extn108.0: StateChange: END=A CMCSConnected->CMCSCompleted
5598552mS CMExtnEvt: v=23 State, new=PortRecoverDelay old=Connected,0,0,Extn108
5598553mS CMLOGGING: CALL:2012/01/0316:59,00:00:15,000,108,O,?Extn108,?Extn108,Extn108,,,1,,""n/a,0
5598554mS CD: CALL: 0.2385.0 BState=Connected Cut=2 Music=0.0 Aend="Extn108(108)" (12.25) Bend="?Extn108(Voicemail)" [VoiceMail] (21.42) CalledNum=?Extn108 () CallingNum=108 (Extn108) Internal=1 Time=15139 AState=Idle
5598554mS CD: CALL: 0.2385.0 Deleted
5598554mS CMExtnEvt: Extn108: CALL LOST (CMCauseNormal)
5598554mS CMExtnEvt: Extn108: Extn(108) Calling Party Number(108) Type(CMNTypeInternal)
5598554mS CMExtnEvt: Extn108: CMExtnHandler::SetCurrent( id: 2385->0 )
5598555mS CMCallEvt: 0.2385.0 -1 Extn108.-1: StateChange: END=X CMCSCompleted->CMCSDelete
5598557mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
5598557mS CMCallEvt: 0.2387.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
5598557mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
5598557mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[?Extn108] Type=Voicemail (102) Reason=CMDRX_MailboxAccess Calling[108] Type=Internal Plan=Default
BChan: slot=21 chan=42
Cause=16, Normal call clearing
5598558mS CMMap: a=21.42 b=0.0 T0
5598559mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 2387->0 )
5598559mS CMCallEvt: 0.2387.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
5598559mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
5598559mS 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
5598560mS CMCallEvt: 0.2387.0 -1 BaseEP: DELETE CMEndpoint f4dbd4cc TOTAL NOW=8 CALL_LIST=3
5598560mS CMCallEvt: END CALL:403 (f4d91d94)
5598561mS CMCallEvt: 0.2385.0 -1 BaseEP: DELETE CMEndpoint f4d8d1e4 TOTAL NOW=7 CALL_LIST=3
5598563mS CMMap: PCG::UnmapBChan pcp[32]b1r0 cp_b f578d1f0 other_cp_b f4f30158
5598563mS CMMap: a=12.25 b=21.42 M0
5598563mS CMMap: PCG::UnmapBChan pcp[283]b1r0 cp_b f4f30158 other_cp_b 0
5598627mS T1Channel chan: E&M 14.5: Transmit T1EMOnHook
5598627mS T1CAS: Channel E&M 14.5: TX: 1111 -> 0000
5598627mS T1Channel chan: E&M 14.5: StateChange WinkSending->WinkEndGuard
5599217mS T1Channel chan: E&M 14.5: StateChange WinkEndGuard->WaitForDigits
5600193mS CMCallEvt: 0.2388.0 -1 BaseEP: NEW CMEndpoint f4d8d1e4 TOTAL NOW=8 CALL_LIST=3
5600193mS CMCallEvt: 0.2388.0 -1 Extn108.-1: NEW CMExtnEndpoint f4d8d1e4, Name=Extn108, Extn=108, Phys Extn=108
5600193mS CMCallEvt: CREATE CALL:404 (f4d91d94)
5600193mS CMCallEvt: 0.2389.0 -1 BaseEP: NEW CMEndpoint f4dbfb00 TOTAL NOW=9 CALL_LIST=3
5600195mS CMExtnEvt: Extn108: CMExtnHandler::SetCurrent( id: 0->2388 )
5600196mS CMExtnEvt: v=23 State, new=Idle old=PortRecoverDelay,0,0,Extn108
5600196mS CMExtnRx: v=108, p1=0
CMSetup
Line: type=DigitalExtn 10 Call: lid=0 id=2388 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[108] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn108
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Gloria Garnish
5600196mS CMCallEvt: 0.2388.0 404 Extn108.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
5600197mS CMExtnEvt: v=23 State, new=Dialling old=Idle,0,0,Extn108
5600197mS CMTARGET: 0.2388.0 404 Extn108.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=108 dir=out complete=0 ses=0
5600197mS CMTARGET: 0.2388.0 404 Extn108.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
5600198mS CMCallEvt: 0.2388.0 404 Extn108.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
5600199mS CMExtnTx: v=108, p1=0
CMSetupAck
Line: type=DigitalExtn 10 Call: lid=0 id=2388 in=0
BChan: slot=12 chan=25
Timed: 03/01/12 17:00
5600200mS CD: CALL: 0.2388.0 BState=Idle Cut=1 Music=3.0 Aend="Extn108(108)" (12.25) Bend="" [] (0.0) CalledNum= () CallingNum=108 (Extn108) Internal=1 Time=7 AState=DialInitiated
5600201mS CMMap: a=12.25 b=0.0 D1
5603217mS T1Channel chan: E&M 14.5: StateChange WaitForDigits->Talk
5603217mS T1Dialler: dialler: 0.5: Dial First Digit Timeout 4000
5603218mS T1Line: line: 14.5: State= IncomingGuard RxMessage: Ringing
5603218mS T1Line: line: 14.5: State Change IncomingGuard -> Present
5603219mS T1Line: line: 14.5: Incoming Call from to
5603219mS CMCallEvt: 0.2390.0 -1 BaseEP: NEW CMEndpoint f4d92280 TOTAL NOW=10 CALL_LIST=4
5603220mS CMCallEvt: CREATE CALL:405 (f4dc0f44)
5603220mS CMCallEvt: 0.2391.0 -1 BaseEP: NEW CMEndpoint f4f1e054 TOTAL NOW=11 CALL_LIST=4
5603224mS CMLineRx: v=14
CMSetup
Line: type=T1Line 14 Call: lid=14 id=2390 in=1
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=7 chan=38
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceT1Trunk type=0 number=14 channel=5 rx_gain=32 tx_gain=32 ep_callid=2390 ipaddr=10.1.11.52 apps=0
5603224mS CD: CALL: 14.2390.1 BState=Idle Cut=1 Music=0.0 Aend="Line 14" (7.38) Bend="" [] (0.0) CalledNum= () CallingNum= () Internal=0 Time=4 AState=Idle
5603224mS CMCallEvt: 14.2390.1 405 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSIdle->CMCSDialInitiated
5603225mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: Setting Hard Timer 5000
5603225mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: LOOKUP CALL ROUTE: type=0 called_party= sub= calling= dir=in complete=0 ses=0
5603225mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: SET BESTMATCH: length 0 vs -1 match=213 dest=213
5603225mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: AMBIGUITY: match=222 dest=222
5603225mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: AMBIGUITY: match=209 dest=209
5603226mS CMCallEvt: Priority hike: call 405 priority 0->1
5603226mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: LOOKUP ICR: DDI= CGPN= (Destination 213 ) => CDPN=213
5603226mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: ADD TARGET (N): number=213 type=0 depth=1 nobar=1 setorig=1 ses=0
5603226mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: SET USER: Extn213 orig=1
5603227mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: ADD USER: Extn213 depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeUnknown) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000
5603227mS CMCallEvt: 0.2392.0 -1 BaseEP: NEW CMEndpoint f4dbd4cc TOTAL NOW=12 CALL_LIST=5
5603227mS CMCallEvt: 0.2392.0 -1 Extn213.-1: NEW CMExtnEndpoint f4dbd4cc, Name=Extn213, Extn=213, Phys Extn=213
5603229mS CMTARGET: 0.2392.0 405 Extn213.0: ADD PRIMARY
5603229mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: CancelTimer CMTCDelayedProcessing
5603229mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: INITIAL TARGETING SUCCEEDED
5603230mS CMTARGET: 14.2390.1 405 T1 Trunk:14 CHAN=5: GetNoAnswerTimer:20
5603230mS CMCallEvt: 14.2390.1 405 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSDialInitiated->CMCSDialling
5603231mS CMLineTx: v=14
CMSetupAck
Line: type=T1Line 14 Call: lid=14 id=2390 in=1
5603231mS T1Line: line: 14.5: State: Present CMMessage: CMSetupAck
5603231mS T1Line: line: 14.5: State Change Present -> OverlapRecv
5603231mS CMCallEvt: 0.2391.0 405 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
5603232mS CMCallEvt: 0.2392.0 405 Extn213.0: StateChange: END=T CMCSIdle->CMCSOffering
5603233mS CMExtnEvt: Extn213: CMExtnHandler::SetCurrent( id: 0->2392 )
5603233mS CMExtnTx: v=213, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=2392 in=0
Called[213] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Default Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceT1Trunk type=0 number=14 channel=5 rx_gain=32 tx_gain=32 ep_callid=2390 ipaddr=10.1.11.52 apps=0
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Extn213
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
Display [??>Extn213]
Timed: 03/01/12 17:00
Locale: enu
5603233mS CMExtnRx: v=213, p1=0
CMAlerting
Line: type=DigitalExtn 3 Call: lid=0 id=2392 in=0
5603233mS CMCallEvt: 0.2392.0 405 Extn213.0: StateChange: END=T CMCSOffering->CMCSRinging
5603234mS CMExtnEvt: v=5 State, new=Ringing old=Idle,0,0,Extn213
5603234mS CMCallEvt: 0.2391.0 405 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
5603235mS CMCallEvt: 14.2390.1 405 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSDialling->CMCSRingBack
5603235mS CMLineTx: v=14
CMAlerting
Line: type=T1Line 14 Call: lid=14 id=2390 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn213
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=213
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceStdPhone type=77 number=29 channel=0 rx_gain=32 tx_gain=32 ep_callid=2392 ipaddr=10.1.11.52 apps=0
5603236mS T1Line: line: 14.5: State: OverlapRecv CMMessage: CMAlerting
5603236mS T1Line: line: 14.5: State Change OverlapRecv -> Received
5603237mS CD: CALL: 14.2390.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 14" (7.38) Bend="Extn213(213)" [Extn213(213)] (0.0) CalledNum=213 (Extn213) CallingNum= () Internal=0 Time=17 AState=Ringing
5603240mS CMMap: a=7.38 b=0.0 R1
5603493mS RES: Tue 3/1/2012 17:00:14 FreeMem=57193864(1) CMMsg=5 (8) Buff=5200 939 999 7408 5 Links=24475
5603493mS RES2: IP 500 V2 8.0(16) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=70 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
5603759mS T1DSP: PRIU DSP 4: read tone, port 1, channel 5, tone '2'
5603759mS T1Dialler: dialler: 0.5: Receive DTMF 2
5603760mS T1Line: line: 14.5: State= Received RxMessage: ReceivedDigit
5603760mS T1Line: line: 14.5: Routing Digit Received 2
5603960mS T1DSP: PRIU DSP 4: read tone, port 1, channel 5, tone '2'
5603960mS T1Dialler: dialler: 0.5: Receive DTMF 2
5603961mS T1Line: line: 14.5: State= Received RxMessage: ReceivedDigit
5603961mS T1Line: line: 14.5: Routing Digit Received 2
5604144mS T1DSP: PRIU DSP 4: read tone, port 1, channel 5, tone '2'
5604144mS T1Dialler: dialler: 0.5: Receive DTMF 2
5604145mS T1Line: line: 14.5: State= Received RxMessage: ReceivedDigit
5604145mS T1Line: line: 14.5: Routing Digit Received 2
5604146mS T1Line: line: 14.5: State= Received RxMessage: Ringing
********** Warning: Logging to Screen Stopped **********