E&M Tie between IPO 8.0 & Mitel SX200D

[dialtonetek]

Hello everyone,

Working on an IPO and a Mitel in the same telephone room.
I have them connected via a T1 set as E&M Tie lines.
IPO to Mitel worked as soon as I connected them. The trouble I'm having is getting the Mitel to communicate with the IPO.
I have the channels set as incoming group 3, recieve digits at 3,
Mitel is sending 3, incoming call route is set to Any voice, line group 3, incoming number of 222(test phone in phone room), destination of 222. Another setup for 110(front desk)
IPO channel timers are at default
Mitel is a SX200D 1003 software, timers are set as
Guard timer is 600ms
debounce timer is 100ms
Outpulse delay timer is 800ms
Interdigit time is 800ms
Wait for delay timer is 5000ms
I believe it's a timing issue, I get a prealerting and it seems like it times out and goes to 110.

Any thoughts?

 

[Bas1234]

Sure the wiring is ok, if you twisted -/+ on RX or -/+ on the TX could give strange things. Also Incomming group ID on the trunk is it 0 like the Incomming callroute?

___________________________________________
It works! Now if only I could remember what I did...

Dain Bramaged (Avaya Search tool

http://tinyurl.com/bas1234

)
______________________________________

 

[dialtonetek]

Thanks for the quick response.

IP Office to Mitel works fine, I can talk all day long.
It's Mitel to IP Office is where the problem is. I have the tie lines in incoming line group 3.

 

[mforrence]

Do you see the incoming call attempt within Monitor?

 

[dialtonetek]

I do but i'm not sure how to read it.

 

[dialtonetek]

I also see it in system status and monitor the T1 channels

 

[tlpeter]

build an incoming route with only a destination.
If that works then you need to play with the digits that are send to the IPO.
It will look for a match form the right to left.

When you send 123456789 then 123456789 should be in the incoming callroute.
When there is no match then the IPO looks for a best match but only with less digits, not more.
So i think you do not have the right digits programmed in the incoming callroute.


BAZINGA!

I'm not insane, my mother had me tested!

 

[dialtonetek]

If I have 222 in the incoming digits wouldn't it be a match either way?

 

[tlpeter]

That depends on what is being send by the Mitel.
Make a monitor trace ad post it here so i can have a look.


BAZINGA!

I'm not insane, my mother had me tested!

 

[dialtonetek]

Calling extension 222 and rings at extension 213
I only have those two set up for now.

UNICODE-UTF8
enu

********** Warning: Logging to Screen Started **********
322425040mS T1CAS: Channel E&M 14.5: RX: 0000 -> 1111
322425040mS T1Channel chan: E&M 14.5: Receive T1EMOffHook
322425040mS T1Channel chan: E&M 14.5: EventRx T1EMOffHook state=Idle
322425040mS T1Channel chan: E&M 14.5: StateChange Idle->IncomingHandshake
322425040mS T1DSP: PRIU DSP 4: channel 37 (timeslot 11), speech path on
322425041mS T1Line: line: 14.5: State= Idle RxMessage: Alert_PreIndication
322425042mS T1Line: line: 14.5: State Change Idle -> IncomingGuard
322425060mS T1Channel chan: E&M 14.5: StateChange IncomingHandshake->IncomingAlerting
322425160mS T1Channel chan: E&M 14.5: Transmit T1EMOffHook
322425160mS T1CAS: Channel E&M 14.5: TX: 0000 -> 1111
322425160mS T1Channel chan: E&M 14.5: StateChange IncomingAlerting->WinkSending
322425360mS T1Channel chan: E&M 14.5: Transmit T1EMOnHook
322425360mS T1CAS: Channel E&M 14.5: TX: 1111 -> 0000
322425360mS T1Channel chan: E&M 14.5: StateChange WinkSending->WinkEndGuard
322425560mS T1Channel chan: E&M 14.5: StateChange WinkEndGuard->WaitForDigits
322426560mS T1Channel chan: E&M 14.5: StateChange WaitForDigits->Talk
322426560mS T1Dialler: dialler: -258772428.5: Dial First Digit Timeout 1000
322426561mS T1Line: line: 14.5: State= IncomingGuard RxMessage: Ringing
322426561mS T1Line: line: 14.5: State Change IncomingGuard -> Present
322426562mS T1Line: line: 14.5: Incoming Call from to
322426562mS CMCallEvt: 0.10791.0 -1 BaseEP: NEW CMEndpoint f4dac34c TOTAL NOW=6 CALL_LIST=2
322426562mS CMCallEvt: CREATE CALL:2645 (f4d88938)
322426563mS CMCallEvt: 0.10792.0 -1 BaseEP: NEW CMEndpoint f4da8f90 TOTAL NOW=7 CALL_LIST=2
322426566mS CMLineRx: v=14
CMSetup
Line: type=T1Line 14 Call: lid=14 id=10791 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=10791 ipaddr=10.1.11.52 apps=0
322426567mS CD: CALL: 14.10791.1 BState=Idle Cut=1 Music=0.0 Aend="Line 14" (7.38) Bend="" [] (0.0) CalledNum= () CallingNum= () Internal=0 Time=4 AState=Idle
322426567mS CMCallEvt: 14.10791.1 2645 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSIdle->CMCSDialInitiated
322426567mS CMTARGET: 14.10791.1 2645 T1 Trunk:14 CHAN=5: Setting Hard Timer 5000
322426567mS CMTARGET: 14.10791.1 2645 T1 Trunk:14 CHAN=5: LOOKUP CALL ROUTE: type=0 called_party= sub= calling= dir=in complete=0 ses=0
322426567mS CMTARGET: 14.10791.1 2645 T1 Trunk:14 CHAN=5: SET BESTMATCH: length 0 vs -1 match=213 dest=213
322426568mS CMTARGET: 14.10791.1 2645 T1 Trunk:14 CHAN=5: AMBIGUITY: match=222 dest=222
322426568mS CMCallEvt: Priority hike: call 2645 priority 0->1
322426568mS CMTARGET: 14.10791.1 2645 T1 Trunk:14 CHAN=5: LOOKUP ICR: DDI= CGPN= (Destination 213 ) => CDPN=213
322426568mS CMTARGET: 14.10791.1 2645 T1 Trunk:14 CHAN=5: ADD TARGET (N): number=213 type=0 depth=1 nobar=1 setorig=1 ses=0
322426569mS CMTARGET: 14.10791.1 2645 T1 Trunk:14 CHAN=5: SET USER: Extn213 orig=1
322426569mS CMTARGET: 14.10791.1 2645 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
322426569mS CMCallEvt: 0.10793.0 -1 BaseEP: NEW CMEndpoint f4dce810 TOTAL NOW=8 CALL_LIST=3
322426569mS CMCallEvt: 0.10793.0 -1 Extn213.-1: NEW CMExtnEndpoint f4dce810, Name=Extn213, Extn=213, Phys Extn=213
322426571mS CMTARGET: 0.10793.0 2645 Extn213.0: ADD PRIMARY
322426572mS CMTARGET: 14.10791.1 2645 T1 Trunk:14 CHAN=5: CancelTimer CMTCDelayedProcessing
322426572mS CMTARGET: 14.10791.1 2645 T1 Trunk:14 CHAN=5: INITIAL TARGETING SUCCEEDED
322426572mS CMTARGET: 14.10791.1 2645 T1 Trunk:14 CHAN=5: GetNoAnswerTimer:20
322426572mS CMCallEvt: 14.10791.1 2645 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSDialInitiated->CMCSDialling
322426573mS CMLineTx: v=14
CMSetupAck
Line: type=T1Line 14 Call: lid=14 id=10791 in=1
322426573mS T1Line: line: 14.5: State: Present CMMessage: CMSetupAck
322426573mS T1Line: line: 14.5: State Change Present -> OverlapRecv
322426573mS CMCallEvt: 0.10792.0 2645 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
322426574mS CMCallEvt: 0.10793.0 2645 Extn213.0: StateChange: END=T CMCSIdle->CMCSOffering
322426575mS CMExtnEvt: Extn213: CMExtnHandler::SetCurrent( id: 0->10793 )
322426575mS CMExtnTx: v=213, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=10793 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=10791 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 08:59
Locale: enu
322426575mS CMExtnRx: v=213, p1=0
CMAlerting
Line: type=DigitalExtn 3 Call: lid=0 id=10793 in=0
322426575mS CMCallEvt: 0.10793.0 2645 Extn213.0: StateChange: END=T CMCSOffering->CMCSRinging
322426576mS CMExtnEvt: v=5 State, new=Ringing old=Idle,0,0,Extn213
322426576mS CMCallEvt: 0.10792.0 2645 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
322426577mS CMCallEvt: 14.10791.1 2645 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSDialling->CMCSRingBack
322426578mS CMLineTx: v=14
CMAlerting
Line: type=T1Line 14 Call: lid=14 id=10791 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=10793 ipaddr=10.1.11.52 apps=0
322426578mS T1Line: line: 14.5: State: OverlapRecv CMMessage: CMAlerting
322426578mS T1Line: line: 14.5: State Change OverlapRecv -> Received
322426579mS CD: CALL: 14.10791.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
322426581mS CMMap: a=7.38 b=0.0 R1
322426897mS RES: Tue 3/1/2012 08:59:08 FreeMem=57067192(1) CMMsg=6 (9) Buff=5200 940 999 7415 5 Links=18442
322426898mS RES2: IP 500 V2 8.0(16) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=65 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
322427586mS ISDNL3Evt: v=13 stacknum=13 State, new=ReleaseReq, old=Active id=11
322427619mS ISDNL3Evt: v=13 stacknum=13 State, new=NullState, old=ReleaseReq id=11
322427620mS CMLineRx: v=13
CMReleaseComp
Line: type=Q931Line 13 Call: lid=13 id=11 in=1
BChan: slot=0 chan=3
Cause=16, Normal call clearing
322427620mS CMCallEvt: 13.11.1 2643 Q931 Trunk:13 CHAN=3: StateChange: END=A CMCSConnected->CMCSCompleted
322427622mS CMLOGGING: CALL:2012/01/0308:58,00:00:38,020,2032346794,I,370,3547,,,,0,,""n/a,0
322427622mS CD: CALL: 13.11.1 BState=Connected Cut=2 Music=0.0 Aend="Line 13" (7.4) Bend="Extn370(370)" [VoiceMail] (21.42) CalledNum=#Extn370 (Extn370) CallingNum=2032346794 () Internal=0 Time=38255 AState=Idle
322427622mS CD: CALL: 13.11.1 Deleted
322427623mS CMCallEvt: 13.11.1 -1 Q931 Trunk:13 CHAN=3: StateChange: END=X CMCSCompleted->CMCSDelete
322427623mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
322427623mS CMCallEvt: 0.10790.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
322427624mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
322427624mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#Extn370] Type=Voicemail (102) Reason=107 Calling[2032346794] Type=Unknown Plan=ISDN Pres=Allowed (0)
BChan: slot=21 chan=42
Cause=16, Normal call clearing
322427624mS CMMap: a=21.42 b=0.0 T0
322427625mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 10790->0 )
322427626mS CMCallEvt: 0.10790.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
322427626mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
322427626mS 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
322427626mS CMCallEvt: 0.10790.0 -1 BaseEP: DELETE CMEndpoint f4d914e0 TOTAL NOW=7 CALL_LIST=2
322427627mS CMCallEvt: END CALL:2643 (f4de0934)
322427628mS CMTARGET: ISDN BChannel 3: in-service check = 1
322427628mS CMTARGET: ISDN BChannel 3: in-service check = 1
322427628mS CMCallEvt: 13.11.1 -1 BaseEP: DELETE CMEndpoint f4d8ad5c TOTAL NOW=6 CALL_LIST=2
322427630mS CMMap: PCG::UnmapBChan pcp[32]b1r0 cp_b f579c8c8 other_cp_b f5752760
322427630mS CMMap: a=7.4 b=21.42 M0
322427630mS CMMap: PCG::UnmapBChan pcp[88]b1r0 cp_b f5752760 other_cp_b 0

********** Warning: Logging to Screen Stopped **********

 

[mattKnight]

Can you post the same trace with ISDN layer 1,2 & 3 enabled too please

Take Care

Matt
I have always wished that my computer would be as easy to use as my telephone.
My wish has come true. I no longer know how to use my telephone.

 

[dialtonetek]

Hope this is what your looking for

UNICODE-UTF8
enu

********** Warning: Logging to Screen Started **********
324932428mS CMExtnRx: v=215, p1=0
CMInformation
Line: type=AnalogueExtn 19 Call: lid=0 id=1 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
324933810mS T1CAS: Channel E&M 14.5: RX: 0000 -> 1111
324933810mS T1Channel chan: E&M 14.5: Receive T1EMOffHook
324933810mS T1Channel chan: E&M 14.5: EventRx T1EMOffHook state=Idle
324933810mS T1Channel chan: E&M 14.5: StateChange Idle->IncomingHandshake
324933810mS T1DSP: PRIU DSP 4: channel 37 (timeslot 11), speech path on
324933811mS T1Line: line: 14.5: State= Idle RxMessage: Alert_PreIndication
324933812mS T1Line: line: 14.5: State Change Idle -> IncomingGuard
324933830mS T1Channel chan: E&M 14.5: StateChange IncomingHandshake->IncomingAlerting
324933930mS T1Channel chan: E&M 14.5: Transmit T1EMOffHook
324933930mS T1CAS: Channel E&M 14.5: TX: 0000 -> 1111
324933930mS T1Channel chan: E&M 14.5: StateChange IncomingAlerting->WinkSending
324934130mS T1Channel chan: E&M 14.5: Transmit T1EMOnHook
324934130mS T1CAS: Channel E&M 14.5: TX: 1111 -> 0000
324934130mS T1Channel chan: E&M 14.5: StateChange WinkSending->WinkEndGuard
324934330mS T1Channel chan: E&M 14.5: StateChange WinkEndGuard->WaitForDigits
324935330mS T1Channel chan: E&M 14.5: StateChange WaitForDigits->Talk
324935330mS T1Dialler: dialler: -258772428.5: Dial First Digit Timeout 1000
324935331mS T1Line: line: 14.5: State= IncomingGuard RxMessage: Ringing
324935331mS T1Line: line: 14.5: State Change IncomingGuard -> Present
324935332mS T1Line: line: 14.5: Incoming Call from to
324935332mS CMCallEvt: 0.11286.0 -1 BaseEP: NEW CMEndpoint f4da1f04 TOTAL NOW=6 CALL_LIST=2
324935333mS CMCallEvt: CREATE CALL:2773 (f4de0934)
324935333mS CMCallEvt: 0.11287.0 -1 BaseEP: NEW CMEndpoint f4da8f90 TOTAL NOW=7 CALL_LIST=2
324935336mS CMLineRx: v=14
CMSetup
Line: type=T1Line 14 Call: lid=14 id=11286 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=11286 ipaddr=10.1.11.52 apps=0
324935336mS CD: CALL: 14.11286.1 BState=Idle Cut=1 Music=0.0 Aend="Line 14" (7.38) Bend="" [] (0.0) CalledNum= () CallingNum= () Internal=0 Time=3 AState=Idle
324935336mS CMCallEvt: 14.11286.1 2773 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSIdle->CMCSDialInitiated
324935336mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: Setting Hard Timer 5000
324935336mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: LOOKUP CALL ROUTE: type=0 called_party= sub= calling= dir=in complete=0 ses=0
324935337mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: SET BESTMATCH: length 0 vs -1 match=213 dest=213
324935337mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: AMBIGUITY: match=222 dest=222
324935337mS CMCallEvt: Priority hike: call 2773 priority 0->1
324935337mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: LOOKUP ICR: DDI= CGPN= (Destination 213 ) => CDPN=213
324935338mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: ADD TARGET (N): number=213 type=0 depth=1 nobar=1 setorig=1 ses=0
324935338mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: SET USER: Extn213 orig=1
324935338mS CMTARGET: 14.11286.1 2773 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
324935339mS CMCallEvt: 0.11288.0 -1 BaseEP: NEW CMEndpoint f4dd0db8 TOTAL NOW=8 CALL_LIST=3
324935339mS CMCallEvt: 0.11288.0 -1 Extn213.-1: NEW CMExtnEndpoint f4dd0db8, Name=Extn213, Extn=213, Phys Extn=213
324935340mS CMTARGET: 0.11288.0 2773 Extn213.0: ADD PRIMARY
324935340mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: CancelTimer CMTCDelayedProcessing
324935341mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: INITIAL TARGETING SUCCEEDED
324935341mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: GetNoAnswerTimer:20
324935341mS CMCallEvt: 14.11286.1 2773 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSDialInitiated->CMCSDialling
324935342mS CMLineTx: v=14
CMSetupAck
Line: type=T1Line 14 Call: lid=14 id=11286 in=1
324935342mS T1Line: line: 14.5: State: Present CMMessage: CMSetupAck
324935342mS T1Line: line: 14.5: State Change Present -> OverlapRecv
324935342mS CMCallEvt: 0.11287.0 2773 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
324935343mS CMCallEvt: 0.11288.0 2773 Extn213.0: StateChange: END=T CMCSIdle->CMCSOffering
324935343mS CMExtnEvt: Extn213: CMExtnHandler::SetCurrent( id: 0->11288 )
324935344mS CMExtnTx: v=213, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=11288 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=11286 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 09:40
Locale: enu
324935344mS CMExtnRx: v=213, p1=0
CMAlerting
Line: type=DigitalExtn 3 Call: lid=0 id=11288 in=0
324935344mS CMCallEvt: 0.11288.0 2773 Extn213.0: StateChange: END=T CMCSOffering->CMCSRinging
324935344mS CMExtnEvt: v=5 State, new=Ringing old=Idle,0,0,Extn213
324935345mS CMCallEvt: 0.11287.0 2773 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
324935345mS CMCallEvt: 14.11286.1 2773 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSDialling->CMCSRingBack
324935346mS CMLineTx: v=14
CMAlerting
Line: type=T1Line 14 Call: lid=14 id=11286 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=11288 ipaddr=10.1.11.52 apps=0
324935346mS T1Line: line: 14.5: State: OverlapRecv CMMessage: CMAlerting
324935346mS T1Line: line: 14.5: State Change OverlapRecv -> Received
324935347mS CD: CALL: 14.11286.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=14 AState=Ringing
324935349mS CMMap: a=7.38 b=0.0 R1
324935397mS RES: Tue 3/1/2012 09:40:57 FreeMem=57063580(2) CMMsg=6 (9) Buff=5200 940 998 7423 5 Links=18430
324935398mS RES2: IP 500 V2 8.0(16) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=67 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034

********** Warning: Logging to Screen Stopped **********

 

[mattKnight]

We don't use wink start in the UK (and Europe)

however

324933830mS T1Channel chan: E&M 14.5: StateChange IncomingHandshake->IncomingAlerting
324933930mS T1Channel chan: E&M 14.5: Transmit T1EMOffHook
324933930mS T1CAS: Channel E&M 14.5: TX: 0000 -> 1111
324933930mS T1Channel chan: E&M 14.5: StateChange IncomingAlerting->WinkSending
324934130mS T1Channel chan: E&M 14.5: Transmit T1EMOnHook
324934130mS T1CAS: Channel E&M 14.5: TX: 1111 -> 0000
324934130mS T1Channel chan: E&M 14.5: StateChange WinkSending->WinkEndGuard
324934330mS T1Channel chan: E&M 14.5: StateChange WinkEndGuard->WaitForDigits
324935330mS T1Channel chan: E&M 14.5: StateChange WaitForDigits->Talk
324935330mS T1Dialler: dialler: -258772428.5: Dial First Digit Timeout 1000
324935331mS T1Line: line: 14.5: State= IncomingGuard RxMessage: Ringing
324935331mS T1Line: line: 14.5: State Change IncomingGuard -> Present
324935332mS T1Line: line: 14.5: Incoming Call from to

It seems to me that either the Mitel isn't sending, or the IPO isn't receiving the DNIS digits. I can see a Timeout waiting for the digits (maybe the IPO is timing out too quickly)

324935337mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: SET BESTMATCH: length 0 vs -1 match=213 dest=213

This also suggests that there are no digits

Take Care

Matt
I have always wished that my computer would be as easy to use as my telephone.
My wish has come true. I no longer know how to use my telephone.

 

[dialtonetek]

324935336mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: LOOKUP CALL ROUTE: type=0 called_party= sub= calling= dir=in complete=0 ses=0
324935337mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: SET BESTMATCH: length 0 vs -1 match=213 dest=213
324935337mS CMTARGET: 14.11286.1 2773 T1 Trunk:14 CHAN=5: AMBIGUITY: match=222 dest=222
324935337mS CM


AMBIGUITY: match=222 dest=222
What does this mean?
Can you point me in the right direction of what timer I can adjust?

 

[tlpeter]

Your dial delay timer is set to 1 (1000ms)
Change this to 4 and try again.


BAZINGA!

I'm not insane, my mother had me tested!

 

[dialtonetek]

Thanks, I will change it.
I will not be able to upload it till a conf call is finished.
I will post the results.

 

[hairlessupportmonkey]

just change it on a merge.....

ACSS - SME
General Geek

 

[dialtonetek]

No luck, I added incoming call route for extension 209.
Called extension 209 and still ended up at extension 213.
Also the change added a pause before 213 started to ring?

UNICODE-UTF8
enu

********** Warning: Logging to Screen Started **********
369469mS T1CAS: Channel E&M 14.5: RX: 0000 -> 1111
369469mS T1Channel chan: E&M 14.5: Receive T1EMOffHook
369469mS T1Channel chan: E&M 14.5: EventRx T1EMOffHook state=Idle
369469mS T1Channel chan: E&M 14.5: StateChange Idle->IncomingHandshake
369470mS T1DSP: PRIU DSP 4: channel 37 (timeslot 11), speech path on
369470mS T1Line: line: 14.5: State= Idle RxMessage: Alert_PreIndication
369471mS T1Line: line: 14.5: State Change Idle -> IncomingGuard
369489mS T1Channel chan: E&M 14.5: StateChange IncomingHandshake->IncomingAlerting
369589mS T1Channel chan: E&M 14.5: Transmit T1EMOffHook
369589mS T1CAS: Channel E&M 14.5: TX: 0000 -> 1111
369589mS T1Channel chan: E&M 14.5: StateChange IncomingAlerting->WinkSending
369789mS T1Channel chan: E&M 14.5: Transmit T1EMOnHook
369789mS T1CAS: Channel E&M 14.5: TX: 1111 -> 0000
369789mS T1Channel chan: E&M 14.5: StateChange WinkSending->WinkEndGuard
369989mS T1Channel chan: E&M 14.5: StateChange WinkEndGuard->WaitForDigits
370642mS ISDNL2Tx: v=13 peb=13
0000 00 01 01 53 ...S
370642mS ISDNL1Tx: v=13 peb=13
0000 00 01 01 53 ...S
370647mS ISDNL1Rx: v=13 peb=13
0000 00 01 01 4b ...K
370647mS ISDNL2Rx: v=13 peb=13
0000 00 01 01 4b ...K
372904mS CMExtnRx: v=323, p1=0
CMReleaseComp
Line: type=DigitalExtn 11 Call: lid=0 id=1058 in=0
372904mS CMCallEvt: 0.1058.0 20 Extn323.0: StateChange: END=A CMCSConnected->CMCSCompleted
372905mS CMExtnEvt: v=17 State, new=PortRecoverDelay old=Connected,0,0,Extn323
372907mS CMLOGGING: CALL:2012/01/0312:46,00:01:43,003,323,O,137,137,Extn323,,,1,,""n/a,0
372907mS CD: CALL: 0.1058.0 BState=Connected Cut=2 Music=0.0 Aend="Extn323(323)" (13.19) Bend="Extn137(137)" [Extn336(336)] (20.12) CalledNum=137 (Extn137) CallingNum=323 (Extn323) Internal=1 Time=108628 AState=Idle
372907mS CD: CALL: 0.1058.0 Deleted
372907mS CMExtnEvt: Extn323: CALL LOST (CMCauseNormal)
372908mS CMExtnEvt: Extn323: Extn(323) Calling Party Number(323) Type(CMNTypeInternal)
372908mS CMExtnEvt: Extn323: CMExtnHandler::SetCurrent( id: 1058->0 )
372909mS CMCallEvt: 0.1058.0 -1 Extn323.-1: StateChange: END=X CMCSCompleted->CMCSDelete
372910mS CMExtnEvt: Extn336: CALL LOST (CMCauseNormal)
372910mS CMExtnEvt: Extn336: Extn(336) Calling Party Number(323) Type(CMNTypeInternal)
372910mS CMCallEvt: 0.1061.0 -1 Extn336.0: StateChange: END=X CMCSConnected->CMCSCompleted
372910mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Connected,0,0,Extn336
372911mS CMExtnTx: v=336, p1=0
CMReleaseComp
Line: type=DigitalExtn 5 Call: lid=0 id=1061 in=0
Called[137] Type=Default (100) Reason=CMDRdirect Calling[323] Type=Internal Plan=Default
Cause=16, Normal call clearing
Timed: 03/01/12 12:48
372911mS CMExtnEvt: Extn336: CMExtnHandler::SetCurrent( id: 1061->0 )
372912mS CMCallEvt: 0.1061.0 -1 Extn336.-1: StateChange: END=X CMCSCompleted->CMCSDelete
372912mS CMCallEvt: 0.1061.0 -1 BaseEP: DELETE CMEndpoint f4d98bd4 TOTAL NOW=10 CALL_LIST=4
372912mS CMCallEvt: END CALL:20 (f4da5960)
372913mS CMCallEvt: 0.1058.0 -1 BaseEP: DELETE CMEndpoint f4dad240 TOTAL NOW=9 CALL_LIST=4
372916mS CMMap: PCG::UnmapBChan pcp[280]b1r0 cp_b f4f2e8f0 other_cp_b f4f46a2c
372916mS CMMap: a=13.19 b=20.12 M0
372916mS CMMap: PCG::UnmapBChan pcp[253]b1r0 cp_b f4f46a2c other_cp_b 0
372919mS PRN: Config Write Wake Up
373319mS RES: Tue 3/1/2012 12:48:47 FreeMem=57311584(1) CMMsg=5 (8) Buff=5200 940 999 7423 5 Links=24877
373319mS RES2: IP 500 V2 8.0(16) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=73 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
373419mS PRN: Updates IO list size 1 updated list size 1
373420mS PRN: Sending Updates out to f4dd92ac started
373421mS PRN: Sending Updates out to f4dd92ac finished
373421mS PRN: Config Write Completed
373989mS T1Channel chan: E&M 14.5: StateChange WaitForDigits->Talk
373989mS T1Dialler: dialler: 987654321.5: Dial First Digit Timeout 4000
373990mS T1Line: line: 14.5: State= IncomingGuard RxMessage: Ringing
373990mS T1Line: line: 14.5: State Change IncomingGuard -> Present
373991mS T1Line: line: 14.5: Incoming Call from to
373991mS CMCallEvt: 0.1090.0 -1 BaseEP: NEW CMEndpoint f4d85170 TOTAL NOW=10 CALL_LIST=4
373992mS CMCallEvt: CREATE CALL:30 (f4da5960)
373992mS CMCallEvt: 0.1091.0 -1 BaseEP: NEW CMEndpoint f4f19db0 TOTAL NOW=11 CALL_LIST=4
373996mS CMLineRx: v=14
CMSetup
Line: type=T1Line 14 Call: lid=14 id=1090 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=1090 ipaddr=10.1.11.52 apps=0
373996mS CD: CALL: 14.1090.1 BState=Idle Cut=1 Music=0.0 Aend="Line 14" (7.38) Bend="" [] (0.0) CalledNum= () CallingNum= () Internal=0 Time=4 AState=Idle
373996mS CMCallEvt: 14.1090.1 30 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSIdle->CMCSDialInitiated
373997mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: Setting Hard Timer 5000
373997mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: LOOKUP CALL ROUTE: type=0 called_party= sub= calling= dir=in complete=0 ses=0
373997mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: SET BESTMATCH: length 0 vs -1 match=213 dest=213
373997mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: AMBIGUITY: match=222 dest=222
373997mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: AMBIGUITY: match=209 dest=209
373997mS CMCallEvt: Priority hike: call 30 priority 0->1
373998mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: LOOKUP ICR: DDI= CGPN= (Destination 213 ) => CDPN=213
373998mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: ADD TARGET (N): number=213 type=0 depth=1 nobar=1 setorig=1 ses=0
373998mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: SET USER: Extn213 orig=1
373999mS CMTARGET: 14.1090.1 30 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
373999mS CMCallEvt: 0.1092.0 -1 BaseEP: NEW CMEndpoint f4dad240 TOTAL NOW=12 CALL_LIST=5
373999mS CMCallEvt: 0.1092.0 -1 Extn213.-1: NEW CMExtnEndpoint f4dad240, Name=Extn213, Extn=213, Phys Extn=213
374001mS CMTARGET: 0.1092.0 30 Extn213.0: ADD PRIMARY
374001mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: CancelTimer CMTCDelayedProcessing
374001mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: INITIAL TARGETING SUCCEEDED
374002mS CMTARGET: 14.1090.1 30 T1 Trunk:14 CHAN=5: GetNoAnswerTimer:20
374002mS CMCallEvt: 14.1090.1 30 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSDialInitiated->CMCSDialling
374003mS CMLineTx: v=14
CMSetupAck
Line: type=T1Line 14 Call: lid=14 id=1090 in=1
374003mS T1Line: line: 14.5: State: Present CMMessage: CMSetupAck
374003mS T1Line: line: 14.5: State Change Present -> OverlapRecv
374003mS CMCallEvt: 0.1091.0 30 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
374004mS CMCallEvt: 0.1092.0 30 Extn213.0: StateChange: END=T CMCSIdle->CMCSOffering
374004mS CMExtnEvt: Extn213: CMExtnHandler::SetCurrent( id: 0->1092 )
374005mS CMExtnTx: v=213, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=1092 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=1090 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 12:48
Locale: enu
374005mS CMExtnRx: v=213, p1=0
CMAlerting
Line: type=DigitalExtn 3 Call: lid=0 id=1092 in=0
374005mS CMCallEvt: 0.1092.0 30 Extn213.0: StateChange: END=T CMCSOffering->CMCSRinging
374005mS CMExtnEvt: v=5 State, new=Ringing old=Idle,0,0,Extn213
374006mS CMCallEvt: 0.1091.0 30 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
374007mS CMCallEvt: 14.1090.1 30 T1 Trunk:14 CHAN=5: StateChange: END=A CMCSDialling->CMCSRingBack
374007mS CMLineTx: v=14
CMAlerting
Line: type=T1Line 14 Call: lid=14 id=1090 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=1092 ipaddr=10.1.11.52 apps=0
374008mS T1Line: line: 14.5: State: OverlapRecv CMMessage: CMAlerting
374008mS T1Line: line: 14.5: State Change OverlapRecv -> Received
374009mS CD: CALL: 14.1090.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
374010mS CMMap: a=7.38 b=0.0 R1
374910mS CMExtnEvt: Extn323: Recover Timer reason=CMTRWrapUp
374910mS CMExtnEvt: v=17 State, new=Idle old=PortRecoverDelay,0,0,Extn323
374911mS CMExtnTx: v=323, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 11 Call: lid=0 id=-1 in=0
Called[Extn323 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn323 Msgs=0]
Timed: 03/01/12 12:48
374912mS CMExtnEvt: Extn336: Recover Timer reason=CMTRWrapUp
374912mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Extn336
374913mS CMExtnTx: v=336, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 5 Call: lid=0 id=-1 in=0
Called[Extn336 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn336 Msgs=0]
Timed: 03/01/12 12:48

********** Warning: Logging to Screen Stopped **********

 

[mattKnight]

I doubt that adding call routes is going to help as the I don't think the IPO is not seeing any DNIS information...

Guard timer is 600ms
debounce timer is 100ms
Outpulse delay timer is 800ms
Interdigit time is 800ms
Wait for delay timer is 5000ms

I assume these are the settings on the MITEL?

Guard timer is 50ms
Outpulse delay timer is 800ms
Interdigit time is 5000ms

AS you can see there are significant differences!

The timings should be on the LINE/timing tab - the other values are listed here

http://www.carrollcommunications.com/manager/t1channels.html

Take Care

Matt
I have always wished that my computer would be as easy to use as my telephone.
My wish has come true. I no longer know how to use my telephone.

 

[dialtonetek]

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 **********