Twinning on IP500V1 6.0(8)

[bdelmar]

Just upgraded from 5.0(8) and now the twinning is having issues. Problem we are experiencing is when you get a call to your ezxtension, the call will twin to your cell phone. When you answer your cell phone, you just get a dial tone and the call still continues to ring at the desk. Or if you answer the call at your desk, your cell phone will continue to ring even after you've answer the call at your desk.

This is an IP500V1 with a IP500 PRI card, not an older PRI card on a legacy carrier.

Anyone experience this issue?

 

[tlpeter]

Nope, works fine for me.
Are your FNE shortcode configured right ?


Homo sapiens non urinat in ventum

honey, i fried the IP Office !!!

 

[bdelmar]

The FNE shortcode is configured correctly. That shouldnt matter as we are not using the mobile call control feature on mobility. Just trying to get an incoming call twinned to the cell phone and answer that call on the cell phone. It worked prior to upgrading to 6.0(8).

The FNE shortcode is

code: *99
number: 31
feature: FNE service

 

[tlpeter]

Is the pri a real pri ?

Homo sapiens non urinat in ventum

honey, i fried the IP Office !!!

 

[bdelmar]

Its an integrated circuit (voice and data). The twinning was working fine prior to upgrading to Release 5.

 

[bdelmar]

I meant prior to upgrading to Release 6, sorry about that.

 

[bdelmar]

Here is a monitor trace. I called in to 8474188117, the call was twinned to 8477211098. I answered the call at my desk. While I was on the call, my cell phone where the call was twinned to continued to ring. After I hung up my desk phone, my cell phone still continued to ring.


********** Reconnect Selected **********

********** contact lost with 10.137.1.2 at 14:23:35 26/5/2010 - reselect = 0 **********
******************************************************************

********** SysMonitor v8.0 (8) **********

********** contact made with 10.137.1.2 at 14:23:36 26/5/2010 **********

********** System (10.137.1.2) has been up and running for 1day, 1hr, 10mins and 24secs(90624524mS) **********

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


********** Warning: TEXT Logging to File STOPPED on 26/5/2010 14:23:36 **********
90624524mS PRN: Monitor Started IP=10.137.10.108 IP 500 6.0(8)
(IP Office: Supports Unicode, System Locale is enu)
90624524mS PRN: LAW=U PRI=1, BRI=0, ALOG=4, ADSL=0 VCOMP=32, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=1 VMAIL=1(VER=3 TYP=1) CALLS=0(TOT=385)
90628524mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
90628524mS PRN: + loader: 1.3
90628524mS PRN: + cpu: id 2 board c0 pld 17 type c10 options 802
90628524mS PRN: + fpga: id 1 issue 1 build 817
90628524mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
90628524mS PRN: ++++++++++++++++++++ LIST OF MODULES ++++++++++++++++++++
90628525mS PRN: +------------------------------------------------------------
90628525mS PRN: + Slot 1: Base DIGSTA8 Board=0x12 PLD=0x06
90628525mS PRN: + Mezzanine PRIS U Board=0x03 PLD=0x0a
90628525mS PRN: +------------------------------------------------------------
90628525mS PRN: + Slot 2: Base PHONE8 Board=0x03 PLD=0x03
90628525mS PRN: + Mezzanine ATM4 Board=0x02 PLD=0x07
90628526mS PRN: +------------------------------------------------------------
90628526mS PRN: + Slot 3: Base VCM32 Board=0x05 PLD=0x11
90628526mS PRN: + Mezzanine NONE
90628526mS PRN: +------------------------------------------------------------
90628526mS PRN: + Slot 4: Base NONE
90628526mS PRN: + Mezzanine NONE
90628526mS PRN: +------------------------------------------------------------
90628526mS PRN: +++++++++++++++++ END OF LIST OF MODULES +++++++++++++++
90634527mS ISDNL1Rx: v=1 peb=1
0000 02 01 92 6a 08 02 0b 50 05 04 03 80 90 a2 18 03 ...j...P........
0010 a9 83 81 6c 0c 21 83 33 31 32 35 36 30 38 36 36 ...l.!.312560866
0020 36 70 05 80 38 31 31 37 6p..8117
90634527mS ISDNL2Rx: v=1 peb=1
0000 02 01 92 6a 08 02 0b 50 05 04 03 80 90 a2 18 03 ...j...P........
0010 a9 83 81 6c 0c 21 83 33 31 32 35 36 30 38 36 36 ...l.!.312560866
0020 36 70 05 80 38 31 31 37 6p..8117
90634527mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0B50(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 80 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 81 .....
InformationElement = CallingPartyNumber
0000 6c 0c 21 83 33 31 32 35 36 30 38 36 36 36 l.!.3125608666
InformationElement = CalledPartyNumber
0000 70 05 80 38 31 31 37 p..8117
90634528mS CMTARGET: ISDN BChannel 1: in-service check = 1
90634528mS ISDNL3Evt: v=1 stacknum=1 State, new=Present, old=NullState id=16
90634529mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 94 ....
90634529mS CMCallPkt: v=0
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=16 in=1
Called[8117] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[3125608666] Type=National Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=1
90634529mS CMLineRx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=1 id=16 in=1
Called[8117] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[913125608666] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=1
90634529mS PRN: Q931Trunk: Found QBChannel to match 0.1 --> 1.2
90634530mS CMCallEvt: 0.2389.0 -1 BaseEP: NEW CMEndpoint f54c42c0 TOTAL NOW=1 CALL_LIST=0
90634530mS CMCallEvt: CREATE CALL:386 (f544dff4)
90634530mS CMCallEvt: 0.2390.0 -1 BaseEP: NEW CMEndpoint f54cdc40 TOTAL NOW=2 CALL_LIST=0
90634532mS CD: CALL: 1.16.1 BState=Idle Cut=1 Music=0.0 Aend="Line 1" (1.2) Bend="" [] (0.0) CalledNum=8117 () CallingNum=913125608666 () Internal=0 Time=2 AState=Idle
90634532mS CD2: CALL:S 1.16.1,0.2390.0,0,0,1,0,0,0,Line 1,,,1.2,0.0,100.0,8117,0.0,913125608666,,,100,,100,,0,16,0,1,0.0,,,,,,,0,2,0,0,0,0,,,0,,0,0,0,0,,386,0,0,,0,,3,0,0,0,0,0,0,1,618,1,,,,,,,,,,,,,,,
90634533mS CMCallEvt: 1.16.1 386 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSIdle->CMCSDialInitiated
90634533mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: LOOKUP CALL ROUTE: type=0 called_party=8117 sub= calling=913125608666 dir=in complete=1 ses=0
90634533mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: SET BESTMATCH: length 0 vs -1 match= dest=VM:AutoAttendant
90634533mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: SET BESTMATCH: length 4 vs 0 match=8117 dest=117
90634534mS CMCallEvt: Priority hike: call 386 priority 0->1
90634534mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: LOOKUP ICR: DDI= CGPN=913125608666 (Destination 117 ) => CDPN=117
90634534mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: ADD TARGET (N): number=117 type=0 depth=1 nobar=1 setorig=1 ses=0
90634534mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: SET USER: BDelmar orig=1
90634535mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: ADD USER: BDelmar depth=2 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeUnknown) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000
90634535mS CMCallEvt: 0.2391.0 -1 BaseEP: NEW CMEndpoint f540daf4 TOTAL NOW=3 CALL_LIST=1
90634535mS CMCallEvt: 0.2391.0 -1 BDelmar.-1: NEW CMExtnEndpoint f540daf4, Name=BDelmar, Extn=117, Phys Extn=114
90634536mS CMTARGET: 0.2391.0 386 BDelmar.0: ADD TWINNED PRIMARY
90634536mS CM: CMGhostHandler : CreateTwinCall
90634537mS CMCallEvt: 0.2392.0 -1 BaseEP: NEW CMEndpoint f5450194 TOTAL NOW=4 CALL_LIST=1
90634537mS CMCallEvt: CREATE CALL:387 (f54cf04c)
90634537mS CMCallEvt: 0.2393.0 -1 BaseEP: NEW CMEndpoint f5416b5c TOTAL NOW=5 CALL_LIST=1
90634539mS CMCallEvt: 0.2392.0 387 GHOST:: StateChange: END=A CMCSIdle->CMCSDialInitiated
90634539mS CMTARGET: 0.2392.0 387 GHOST:: LOOKUP CALL ROUTE: type=100 called_party=918477211098 sub= calling=913125608666 dir=out complete=1 ses=0
90634539mS CMTARGET: 0.2392.0 387 GHOST:: ADD TARGET (N): number=918477211098 type=100 depth=1 nobar=1 setorig=1 ses=0
90634540mS CMTARGET: 0.2392.0 387 GHOST:: SYS SC: 918477211098 2 18477211098 sc=type=Dial code=9N, num=N callinfop->sending_complete=1 secondary_dialtone=
90634540mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
90634540mS CMARS: MakeCallTarget - Called Number: 18477211098
90634540mS CMCallEvt: 0.2394.0 -1 BaseEP: NEW CMEndpoint f54c5b88 TOTAL NOW=6 CALL_LIST=2
90634541mS CMTARGET: 0.2392.0 387 GHOST:: INITIAL TARGETING SUCCEEDED
90634541mS CMTARGET: 0.2392.0 387 GHOST:: GetNoAnswerTimer:15
90634541mS CMCallEvt: 0.2392.0 387 GHOST:: StateChange: END=A CMCSDialInitiated->CMCSDialled
90634542mS CMCallEvt: 0.2393.0 387 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
90634542mS CMCallEvt: 0.2394.0 387 ARS for [9]: StateChange: END=T CMCSIdle->CMCSOffering
90634543mS CMCallEvt: 0.2393.0 387 TargetingEP: RequestEnd 0.2394.0 387 ARS for [9]
90634543mS CMTARGET: 0.2392.0 387 GHOST:: CancelTimer CMTCNoAnswerTimeout
90634543mS CMCallEvt: 0.2394.0 387 ARS for [9]: StateChange: END=B CMCSOffering->CMCSOverlapRecv
90634544mS CMARS: FORM: [9] - Received Number: 18477211098
90634544mS CMARS: FOUND A SHORT CODE - short_code: 1N; - Tel: 1Nsi8472050088 - Called_Party: 18477211098 - Line Group Id: 0
90634544mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
90634544mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
90634545mS CMCallEvt: 0.2395.0 -1 BaseEP: NEW CMEndpoint f541a84c TOTAL NOW=7 CALL_LIST=2
90634546mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 18477211098 - Calling Number: 913125608666
90634546mS CMARS: SEND Setup TO LINE
90634547mS CMCallEvt: 0.2395.0 387 Q931 Trunk:1 CHAN=12: StateChange: END=child CMCSIdle->CMCSOffering
90634547mS CMCallEvt: 0.2393.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
90634547mS CMCallEvt: 0.2393.0 -1 BaseEP: DELETE CMEndpoint f5416b5c TOTAL NOW=6 CALL_LIST=2
90634547mS CMExtnEvt: BDelmar: ExtnFault now 0
90634549mS SS : 0.2392.0 387 GHOST: CMSSEndpoint::MakeActive, SetLocalTone to CMLocalToneCutThru...
90634549mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: INITIAL TARGETING SUCCEEDED
90634549mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: GetNoAnswerTimer:35
90634550mS CMCallEvt: 1.16.1 386 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSDialInitiated->CMCSDialled
90634550mS CMLineTx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=1 id=16 in=1
BChan: slot=0 chan=1
90634550mS CMCallEvt: 0.2390.0 386 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
90634551mS CMCallEvt: 0.2391.0 386 BDelmar.0: StateChange: END=T CMCSIdle->CMCSOffering
90634552mS CMExtnEvt: BDelmar: CMExtnHandler::SetCurrent( id: 0->2391 )
90634552mS CMExtnTx: v=117, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=2391 in=0
Called[117] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[913125608666] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:1 R:4) number=913125608666
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=6 class=CMDeviceISDNTrunk type=2 number=1 channel=1 rx_gain=32 tx_gain=32 ep_callid=16 ipaddr=10.137.1.2 apps=0
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=BDelmar
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Brad Delmar
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=8117
Display [913125608666>BDelmar]
Timed: 26/05/10 14:23
Locale: enu
90634552mS CMExtnRx: v=117, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=2391 in=0
90634552mS CMCallEvt: 0.2391.0 386 BDelmar.0: StateChange: END=T CMCSOffering->CMCSRinging
90634553mS CMExtnEvt: v=4 State, new=Ringing old=Idle,0,0,BDelmar
90634554mS CMCallEvt: 0.2390.0 386 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
90634555mS CMCallEvt: 1.16.1 386 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSDialled->CMCSRingBack
90634555mS CMLineTx: v=1
CMAlerting
Line: type=Q931Line 1 Call: lid=1 id=16 in=1
BChan: slot=0 chan=1
90634556mS CD: CALL: 1.16.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.2) Bend="BDelmar(117)" [BDelmar(117)] (0.0) CalledNum=117 (BDelmar) CallingNum=913125608666 () Internal=0 Time=26 AState=Ringing
90634556mS CD2: CALL:S 1.16.1,0.2390.0,1,1,1,2,0,0,Line 1,,BDelmar(117),1.2,0.0,100.0,117,0.0,913125608666,,,0,8117,100,,0,17,0,1,0.0,,,BDelmar,,BDelmar,,0,26,0,2,0,0,enu,,0,,0,0,0,0,,386,0,0,,0,,3,0,0,0,0,0,0,1,618,1,,,,,,,,,,,,,,,
90634557mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 94 ....
90634558mS CMMap: a=1.2 b=0.0 R1
90634559mS ISDNL3Evt: v=1 stacknum=1 State, new=ICProceeding, old=Present id=16
90634559mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0B50(Local)
Message Type = CallProceeding
InformationElement = CHI
0000 18 03 a9 83 81 .....
90634559mS ISDNL2Tx: v=1 peb=1
0000 00 01 6a 94 08 02 8b 50 02 18 03 a9 83 81 ..j....P......
90634560mS ISDNL3Evt: v=1 stacknum=1 State, new=Received, old=ICProceeding id=16
90634560mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0B50(Local)
Message Type = Alerting
90634560mS ISDNL2Tx: v=1 peb=1
0000 00 01 6c 94 08 02 8b 50 01 ..l....P.
90634560mS ISDNL1Tx: v=1 peb=1
0000 00 01 6a 94 08 02 8b 50 02 18 03 a9 83 81 ..j....P......
90634560mS ISDNL1Tx: v=1 peb=1
0000 00 01 6c 94 08 02 8b 50 01 ..l....P.
90634584mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 6c ...l
90634584mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 6c ...l
90634588mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 6e ...n
90634589mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 6e ...n
90634905mS RES: Wed 26/5/2010 14:23:46 FreeMem=69864312(3) CMMsg=7 (8) Buff=200 960 1000 7414 3 Links=23369
90634906mS RES2: IP 500 6.0(8) Tasks=36 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=56 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 CF=UMNT
90635547mS CMCallEvt: 0.2395.0 387 Q931 Trunk:1 CHAN=12: Twin Mobile Delay Timer Expired - Initiate call to Mobile
90635547mS PRN: Setting configured voice gain for ch 12.
90635547mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=2395 in=0
Called[18477211098] Type=National (2) Reason=CMDRdirect Calling[3125608666] Type=National Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=12
90635548mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=2395
90635549mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0039(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 90 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 8c .....
InformationElement = CallingPartyNumber
0000 6c 0c 21 80 33 31 32 35 36 30 38 36 36 36 l.!.3125608666
InformationElement = CalledPartyNumber
0000 70 0c a0 31 38 34 37 37 32 31 31 30 39 38 p..18477211098
90635549mS ISDNL2Tx: v=1 peb=1
0000 00 01 6e 94 08 02 00 39 05 04 03 90 90 a2 18 03 ..n....9........
0010 a9 83 8c 6c 0c 21 80 33 31 32 35 36 30 38 36 36 ...l.!.312560866
0020 36 70 0c a0 31 38 34 37 37 32 31 31 30 39 38 6p..18477211098
90635551mS ISDNL1Tx: v=1 peb=1
0000 00 01 6e 94 08 02 00 39 05 04 03 90 90 a2 18 03 ..n....9........
0010 a9 83 8c 6c 0c 21 80 33 31 32 35 36 30 38 36 36 ...l.!.312560866
0020 36 70 0c a0 31 38 34 37 37 32 31 31 30 39 38 6p..18477211098
90635582mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 70 ...p
90635582mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 70 ...p
90635819mS ISDNL1Rx: v=1 peb=1
0000 02 01 94 70 08 02 80 39 02 18 03 a9 83 8c ...p...9......
90635819mS ISDNL2Rx: v=1 peb=1
0000 02 01 94 70 08 02 80 39 02 18 03 a9 83 8c ...p...9......
90635819mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0039(Local)
Message Type = CallProceeding
InformationElement = CHI
0000 18 03 a9 83 8c .....
90635819mS ISDNL3Evt: v=1 stacknum=1 State, new=Proceeding, old=Initiated id=2395
90635820mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 96 ....
90635820mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 96 ....
90635820mS CMCallPkt: v=0
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=2395 in=0
BChan: slot=0 chan=12
90635820mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=2395 in=0
BChan: slot=0 chan=12
90635821mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
90635821mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
90635821mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.2394.0 387 ARS for [9] - Call State: CMCSOverlapRecv
90635821mS CMCallEvt: 0.2394.0 -1 ARS for [9]: StateChange: END=X CMCSOverlapRecv->CMCSDelete
90635822mS CMCallEvt: 0.2395.0 387 Q931 Trunk:1 CHAN=12: StateChange: END=B CMCSOffering->CMCSAccept
90635822mS CMCallEvt: 0.2394.0 -1 BaseEP: DELETE CMEndpoint f54c5b88 TOTAL NOW=5 CALL_LIST=2
90635823mS CMMap: a=1.13 b=0.0 D1
90636466mS ISDNL1Rx: v=1 peb=1
0000 02 01 96 70 08 02 80 39 03 1e 02 82 88 ...p...9.....
90636466mS ISDNL2Rx: v=1 peb=1
0000 02 01 96 70 08 02 80 39 03 1e 02 82 88 ...p...9.....
90636466mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0039(Local)
Message Type = Progress
InformationElement = PI
0000 1e 02 82 88 ....
90636466mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 98 ....
90636467mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 98 ....
90636467mS CMCallPkt: v=0
CMProgress
Line: type=Q931Line 1 Call: lid=0 id=2395 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
90636467mS CMLineRx: v=1
CMProgress
Line: type=Q931Line 1 Call: lid=0 id=2395 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
90636467mS CMCallEvt: 0.2395.0 387 Q931 Trunk:1 CHAN=12: StateChange: END=B CMCSAccept->CMCSRinging
90636468mS CMCallEvt: 0.2392.0 387 GHOST:: StateChange: END=A CMCSDialled->CMCSRingBack
90637704mS H323Evt: Recv: RegistrationRequest 172.16.22.17; Endpoints registered: 3; Endpoints in registration: 0
90638655mS CMExtnRxP: v=115
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
90638655mS CMExtnEvt: JYared: ProcessShortCode feature=GetSystemInfo id=
90638656mS CMExtnTxP: v=115
CMShortCode
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [6.0 (8)] Calling[BlueWire] Type=Default Plan=Default
Display [IP 500]
Tag type=Text flags=0x1 [1,1,3,1,2,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x32 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 26/05/10 14:23
90638656mS CMExtnTx: v=115, p1=0
CMShortCode
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [6.0 (8)] Calling[BlueWire] Type=Default Plan=Default
Display [IP 500]
Tag type=Text flags=0x1 [1,1,3,1,2,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x32 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 26/05/10 14:23
90643957mS CMExtnRx: v=117, p1=0
CMConnect
Line: type=DigitalExtn 2 Call: lid=0 id=2391 in=0
90643957mS CMCallEvt: 0.2391.0 386 BDelmar.0: StateChange: END=T CMCSRinging->CMCSConnReq
90643958mS CMCallEvt: 0.2390.0 386 TargetingEP: RequestEnd 0.2391.0 386 BDelmar.0
90643958mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: CancelTimer CMTCCoverageTimeout
90643958mS CMCallEvt: 0.2390.0 -1 BaseEP: DELETE CMEndpoint f54cdc40 TOTAL NOW=4 CALL_LIST=2
90643959mS CMExtnEvt: v=4 State, new=Connected old=Ringing,0,0,BDelmar
90643960mS CMCallEvt: 1.16.1 386 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSRingBack->CMCSOGConnReq
90643960mS CMLineTx: v=1
CMConnect
Line: type=Q931Line 1 Call: lid=1 id=16 in=1
Called[117] Type=Internal (101) Reason=CMDRdirect
BChan: slot=0 chan=1
90643961mS CD: CALL: 1.16.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (1.2) Bend="BDelmar(117)" [BDelmar(117)] (20.4) CalledNum=117 (BDelmar) CallingNum=913125608666 () Internal=0 Time=9431 AState=Connected
90643961mS CD2: CALL:S 1.16.1,0.2390.0,2,2,1,0,1,0,Line 1,BDelmar(117),,1.2,0.4,100.0,117,0.0,913125608666,,,0,8117,100,,0,16,0,1,0.0,,,BDelmar,,BDelmar,,0,9431,0,0,0,9404,enu,,0,,0,0,0,0,,386,0,0,,0,,3,0,0,0,0,0,0,1,618,1,,,,,,,,,,,,,,,
90643962mS CMMap: a=1.2 b=0.0 R0
90643962mS CMMap: PCG::MapBChan pcp[89]b1r0 cp_b f5969c1c other_cp_b 0 type CGTypeSimple
90643962mS CMMap: PCG::MapBChan pcp[128]b1r0 cp_b f5537d48 other_cp_b f5969c1c type CGTypeSimple
90643963mS CMMap: a=1.2 b=20.4 M1
90643963mS ISDNL3Evt: v=1 stacknum=1 State, new=ConnReq, old=Received id=16
90643963mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0B50(Local)
Message Type = Connect
90643963mS ISDNL2Tx: v=1 peb=1
0000 00 01 70 98 08 02 8b 50 07 ..p....P.
90643963mS ISDNL1Tx: v=1 peb=1
0000 00 01 70 98 08 02 8b 50 07 ..p....P.
90643986mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 72 ...r
90643986mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 72 ...r
90644018mS ISDNL1Rx: v=1 peb=1
0000 02 01 98 72 08 02 0b 50 0f ...r...P.
90644018mS ISDNL2Rx: v=1 peb=1
0000 02 01 98 72 08 02 0b 50 0f ...r...P.
90644018mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0B50(Remote)
Message Type = ConnectAck
90644019mS ISDNL3Evt: v=1 stacknum=1 State, new=Active, old=ConnReq id=16
90644019mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 9a ....
90644019mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 9a ....
90644020mS CMCallPkt: v=0
CMConnectAck
Line: type=Q931Line 1 Call: lid=0 id=16 in=1
90644020mS CMLineRx: v=1
CMConnectAck
Line: type=Q931Line 1 Call: lid=1 id=16 in=1
90644020mS CMCallEvt: 1.16.1 386 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSOGConnReq->CMCSConnected
90644020mS CMTARGET: 1.16.1 386 Q931 Trunk:1 CHAN=1: ~CMTargetHandler f540aae8 ep f54c42c0
90644021mS CMCallEvt: 0.2391.0 386 BDelmar.0: StateChange: END=B CMCSConnReq->CMCSConnected
90644022mS CMExtnTx: v=117, p1=0
CMConnectAck
Line: type=DigitalExtn 2 Call: lid=0 id=2391 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[913125608666] Type=Unknown Plan=ISDN Pres=Allowed (0)
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:1 R:4) number=913125608666
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=6 class=CMDeviceISDNTrunk type=2 number=1 channel=1 rx_gain=32 tx_gain=32 ep_callid=16 ipaddr=10.137.1.2 apps=0
Timed: 26/05/10 14:23
90644405mS RES: Wed 26/5/2010 14:23:55 FreeMem=69884220(3) CMMsg=7 (8) Buff=200 960 1000 7414 3 Links=23368
90644406mS RES2: IP 500 6.0(8) Tasks=36 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=53 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 CF=UMNT
90651818mS CMExtnRx: v=117, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=2391 in=0
90651819mS CMCallEvt: 0.2391.0 386 BDelmar.0: StateChange: END=B CMCSConnected->CMCSCompleted
90651819mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Connected,0,0,BDelmar
90651820mS CMLOGGING: CALL:2010/05/2614:23,00:00:07,009,913125608666,I,117,8117,,,,0,,""n/a,0
90651821mS CD: CALL: 1.16.1 BState=Disconnecting Cut=1 Music=0.0 Aend="Line 1" (1.2) Bend="BDelmar(117)" [BDelmar(117)] (20.4) CalledNum=117 (BDelmar) CallingNum=913125608666 () Internal=0 Time=17290 AState=Connected
90651821mS CD2: CALL:S 1.16.1,0.2390.0,2,3,1,0,0,0,Line 1,BDelmar(117),,1.2,0.4,100.0,117,0.0,913125608666,,,0,8117,100,,0,16,0,1,0.0,,,BDelmar,,BDelmar,,0,17291,0,0,7798,9404,enu,,0,,0,0,0,0,,386,0,0,,0,,3,0,0,0,0,0,0,1,618,1,,,,,,,,,,,,,,,
90651821mS CD: CALL: 1.16.1 Deleted
90651822mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=1 id=16 in=1
Cause=16, Normal call clearing
90651822mS CMCallEvt: 1.16.1 -1 Q931 Trunk:1 CHAN=1: StateChange: END=X CMCSConnected->CMCSCompleted
90651822mS CMExtnEvt: BDelmar: CALL LOST (CMCauseNormal)
90651823mS CMExtnEvt: BDelmar: Extn(117) Calling Party Number(913125608666) Type(CMNTypeUnknown)
90651823mS CMExtnEvt: BDelmar: CMExtnHandler::SetCurrent( id: 2391->0 )
90651823mS CMCallEvt: 0.2391.0 -1 BDelmar.-1: StateChange: END=X CMCSCompleted->CMCSDelete
90651824mS CMCallEvt: END CALL:386 (f544dff4)
90651824mS CMCallEvt: 0.2391.0 -1 BaseEP: DELETE CMEndpoint f540daf4 TOTAL NOW=3 CALL_LIST=1
90651825mS CMMap: PCG::UnmapBChan pcp[89]b1r0 cp_b f5969c1c other_cp_b f5537d48
90651825mS CMMap: a=20.4 b=1.2 M0
90651825mS CMMap: PCG::UnmapBChan pcp[128]b1r0 cp_b f5537d48 other_cp_b 0
90651826mS ISDNL3Evt: v=1 stacknum=1 State, new=DiscReq, old=Active id=16
90651827mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0B50(Local)
Message Type = Disconnect
InformationElement = CAUSE
0000 08 02 80 90 ....
90651827mS ISDNL2Tx: v=1 peb=1
0000 00 01 72 9a 08 02 8b 50 45 08 02 80 90 ..r....PE....
90651827mS ISDNL1Tx: v=1 peb=1
0000 00 01 72 9a 08 02 8b 50 45 08 02 80 90 ..r....PE....
90651850mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 74 ...t
90651850mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 74 ...t
90651905mS RES: Wed 26/5/2010 14:24:03 FreeMem=69897196(3) CMMsg=7 (8) Buff=200 958 1000 7414 3 Links=23375
90651906mS RES2: IP 500 6.0(8) Tasks=36 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=57 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 CF=UMNT
90652017mS ISDNL1Rx: v=1 peb=1
0000 02 01 9a 74 08 02 0b 50 4d ...t...PM
90652017mS ISDNL2Rx: v=1 peb=1
0000 02 01 9a 74 08 02 0b 50 4d ...t...PM
90652017mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0B50(Remote)
Message Type = Release
90652017mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0B50(Local)
Message Type = ReleaseComplete
90652017mS ISDNL2Tx: v=1 peb=1
0000 00 01 74 9c 08 02 8b 50 5a ..t....PZ
90652018mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=DiscReq id=16
90652018mS ISDNL1Tx: v=1 peb=1
0000 00 01 74 9c 08 02 8b 50 5a ..t....PZ
90652018mS CMCallPkt: v=0
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=16 in=1
BChan: slot=0 chan=1
90652019mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=1 id=16 in=1
BChan: slot=0 chan=1
90652019mS CMCallEvt: 1.16.1 -1 Q931 Trunk:1 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
90652019mS CD2: CALL 1.16.1,0.2390.0,386,,,,,,,,,,,,,,,,0,0,,0,,3,0,0,0,0,0,0,1,618,1
90652019mS CMCallEvt: 1.16.1 -1 BaseEP: DELETE CMEndpoint f54c42c0 TOTAL NOW=2 CALL_LIST=1
90652039mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 76 ...v
90652039mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 76 ...v
90653823mS CMExtnEvt: BDelmar: Recover Timer reason=CMTRWrapUp
90653823mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,BDelmar
90653824mS CM: CMReminder CheckReminder(user=117)
90653824mS CMExtnTx: v=117, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[BDelmar Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [BDelmar Msgs=0]
Timed: 26/05/10 14:24

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


I tried it a second time, answered it on my cell phone instead of my desk phone and when I answered my cell phone I just get a dial tone and my desk phone continues to ring.

 

[Bas1234]

Check the following;

The PRI can NOT be on T1 robbed-bit and E1R2 channels set to Loop Start emulation.

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

Dain Bramaged
___________________________________________

 

[bwav]

Brad
There is an issue in R6 with the Small upgrade License and NI2. It looks like NI2 that are coming from a NUvox or soft switches only but not sure. There is no fix at this time except to change the PRI to 5Ess or getting a full upgrade license 2.

 

[bdelmar]

bbavaya,

You are right, I just found CQ40234 which says, Twinned calls hear dial tone when answered by the twinned device.