Avaya IP Office Caller ID Name Issue When Twinning

Hostilis956 · Nov 2, 2021

Having an issue here with an Avaya IP Office PBX that is running through an Adtran TA904e as a SIP/PRI gateway.
Essentially, caller ID is working normally for inbound and outbound calls, but when twinning the caller ID name is either that of the user who has twinned their extension or of the company itself as dictated in the outbound dialing shortcode.

The "Send original calling party information for Mobile Twinning" box is checked under the System tab, and I believe it is working because the caller ID number and location is being passed through correctly, it's just the name being displayed is not.

Any potential insights into this issue?

critchey · Nov 2, 2021

How are you SIP URI(s) setup? You would need to have a SIP URI setup where you have the local URI and Contact URI set to original caller for forwarded/twinned calls. You also need to verify with your SIP provider they allow you to pass the originator callerID. That is quite often, in my experience, a setting they have to enable on the SIP trunks before it will work.

The truth is just an excuse for lack of imagination.

Hostilis956 · Nov 2, 2021

Well I work for a company that is the SIP provider and we provided the Adtran as the SIP/PRI gateway.
We do allow the passing of the originator caller ID, we do not modify or restrict caller ID and leave that to the upstream carriers.

I apologize because I am not familiar with Avaya IP office so I am unsure how to set the local and contact URI.
I just looked up a document explaining it and that definitely makes sense, I'll wait for the customer to check those settings and see if it has fixed the issue.

Thanks for the help definitely think this is on the right track!

Hostilis956 · Nov 3, 2021

So actually perhaps there is some confusion.
After digging into it a little bit I think I may have jumped the gun.
Since the Avaya IP Office is configured with a PRI trunk to the Adtran TA904 there are no SIP URI settings.

I have a monitor trace I can post as well.

UNICODE-UTF8
enu
709570976mS ISDNL3Evt: v=13 stacknum=13 State, new=ReleaseReq, old=Active id=16182
709570988mS ISDNL3Evt: v=13 stacknum=13 State, new=NullState, old=ReleaseReq id=16182
709570989mS CMLineRx: v=13
CMReleaseComp
Line: type=Q931Line 13 Call: lid=0 id=16182 in=0
BChan: slot=0 chan=22
Cause=16, Normal call clearing
709570989mS CMCallEvt: 0.16182.0 3478 Q931 Trunk:13 CHAN=22: StateChange: END=B CMCSConnected->CMCSCompleted
709570990mS CMLOGGING: CALL:2021/11/0314:25,00:07:05,000,92038368240,O,5872151486,95872151486,RajMonder,,,0,,""n/a,0
709570990mS CMCallEvt: 0.16178.0 3478 GHOST:: CallLost (Ghost @) cause=CMCauseNormal
709570991mS CMCallEvt: 0.16178.0 -1 GHOST:: StateChange: END=X CMCSConnected->CMCSCompleted
709570991mS CMCallEvt: 0.16178.0 -1 GHOST:: StateChange: END=X CMCSCompleted->CMCSDelete
709570991mS CMCallEvt: 0.16178.0 -1 BaseEP: DELETE CMEndpoint f4fc9a20 TOTAL NOW=3 CALL_LIST=1
709570992mS CMCallEvt: 0.16182.0 -1 Q931 Trunk:13 CHAN=22: StateChange: END=X CMCSCompleted->CMCSDelete
709570992mS CMCallEvt: END CALL:3478 (f4f8d910)
709570993mS CMTARGET: ISDN BChannel 22: in-service check = 1
709570993mS CMTARGET: ISDN BChannel 22: in-service check = 1
709570993mS CMCallEvt: 0.16182.0 -1 BaseEP: DELETE CMEndpoint f4fa1180 TOTAL NOW=2 CALL_LIST=1
709570994mS CMMap: PCG::UnmapBChan pcp[94]b1r0 cp_b f58902e4 other_cp_b f5890600
709570994mS CMMap: a=7.23 b=7.24 M02
709570994mS CMMap: PCG::UnmapBChan pcp[93]b1r0 cp_b f5890600 other_cp_b 0
709571091mS CMCallEvt: 0.16183.0 3476 Raj Monder.0: StateChange: END=B CMCSConnected->CMCSCompleted
709571091mS CMExtnEvt: v=-1 State, new=PortRecoverDelay old=Connected,0,0,Raj Monder
709571092mS CMLOGGING: CALL:2021/11/0314:25,00:07:29,012,92038368240,I,600,4032792958,2038368240,,,0,,""n/a,0
709571092mS CD: CALL: 13.1.1 BState=Disconnecting Cut=1 Music=0.0 Aend="Line 13" (7.24) Bend="Raj Monder(633)" [Raj Monder(633)] (0.0) CalledNum=600 (Main) CallingNum=92038368240 (2038368240) Internal=0 Time=459303 AState=Connected
709571093mS CD: CALL: 13.1.1 Deleted
709571093mS CMLineTx: v=13
CMDisconnect
Line: type=Q931Line 13 Call: lid=13 id=1 in=1
Cause=16, Normal call clearing
709571094mS CMCallEvt: 13.1.1 -1 Q931 Trunk:13 CHAN=23: StateChange: END=X CMCSConnected->CMCSCompleted
709571094mS CMExtnEvt: Raj Monder: CALL LOST (CMCauseForceIdle)
709571094mS CMExtnEvt: Raj Monder: Extn(633) Calling Party Number(92038368240) Type(CMNTypeUnknown)
709571094mS CMExtnEvt: Raj Monder: CMExtnHandler::SetCurrent( id: 16183->0 )
709571094mS CMCallEvt: 0.16183.0 -1 Raj Monder.-1: StateChange: END=X CMCSCompleted->CMCSDelete
709571094mS CMCallEvt: END CALL:3476 (f58429bc)
709571095mS CMCallEvt: 0.16183.0 -1 BaseEP: DELETE CMEndpoint f500f7e8 TOTAL NOW=1 CALL_LIST=0
709571095mS CMExtnEvt: Raj Monder:633 ExtnFault now 1
709571097mS ISDNL3Evt: v=13 stacknum=13 State, new=DiscReq, old=Active id=1
709571111mS ISDNL3Evt: v=13 stacknum=13 State, new=NullState, old=DiscReq id=1
709571112mS CMLineRx: v=13
CMReleaseComp
Line: type=Q931Line 13 Call: lid=13 id=1 in=1
BChan: slot=0 chan=23
709571112mS CMCallEvt: 13.1.1 -1 Q931 Trunk:13 CHAN=23: StateChange: END=X CMCSCompleted->CMCSDelete
709571113mS CMTARGET: ISDN BChannel 23: in-service check = 1
709571113mS CMTARGET: ISDN BChannel 23: in-service check = 1
709571113mS CMCallEvt: 13.1.1 -1 BaseEP: DELETE CMEndpoint f5038c68 TOTAL NOW=0 CALL_LIST=0
709571204mS RES: Wed 3/11/2021 14:32:58 FreeMem=59173116(1) CMMsg=7 (8) Buff=5200 960 1000 7423 5 Links=634 CPU=5/10/2411/20435/23841/0
709571204mS RES2: IP 500 V2 8.1(43) Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=59 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=17 TAPI=1 ASC=1 SYS=MNTD OPT=MNTD SDSPD=2034
709575987mS CMTARGET: ISDN BChannel 4: in-service check = 1
709575988mS ISDNL3Evt: v=13 stacknum=13 State, new=Present, old=NullState id=8
709575989mS CMLineRx: v=13
CMSetup
Line: type=Q931Line 13 Call: lid=13 id=8 in=1
Called[4032791631] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[92504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=4
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=iTel Networks
709575989mS PRN: Q931Trunk: Found QBChannel to match 0.4 --> 7.5
709575990mS CMCallEvt: 0.16210.0 -1 BaseEP: NEW CMEndpoint f5038c68 TOTAL NOW=1 CALL_LIST=0
709575990mS CMTARGET: ISDN BChannel 4: in-service check = 1
709575990mS CMTARGET: ISDN BChannel 4: in-service check = 1
709575990mS CMCallEvt: CREATE CALL:3485 (f58429bc)
709575990mS CMCallEvt: 0.16211.0 -1 BaseEP: NEW CMEndpoint f504a694 TOTAL NOW=2 CALL_LIST=0
709575992mS CD: CALL: 13.8.1 BState=Idle Cut=1 Music=0.0 Aend="Line 13" (7.5) Bend="" [] (0.0) CalledNum=4032791631 () CallingNum=92504348484 (iTel Networks) Internal=0 Time=2 AState=Idle
709575992mS CMCallEvt: 13.8.1 3485 Q931 Trunk:13 CHAN=4: StateChange: END=A CMCSIdle->CMCSDialInitiated
709575993mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: LOOKUP CALL ROUTE: type=0 called_party=4032791631 sub= calling=92504348484 dir=in complete=1 ses=0
709575993mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: SET BESTMATCH: length 10 vs -1 match=4032791631 dest=631
709575993mS CMCallEvt: Priority hike: call 3485 priority 0->1
709575993mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: LOOKUP ICR: DDI= CGPN=92504348484 (Destination 631 ) => CDPN=631
709575993mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: ADD TARGET (N): number=631 type=0 depth=1 nobar=1 setorig=1 ses=0
709575993mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: SET USER: Faizel Sunderji orig=1
709575994mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: ADD USER: Faizel Sunderji depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeUnknown) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
709575994mS CMCallEvt: 0.16212.0 -1 BaseEP: NEW CMEndpoint f500f7e8 TOTAL NOW=3 CALL_LIST=1
709575994mS CMCallEvt: 0.16212.0 -1 Faizel Sunderji.-1: NEW CMExtnEndpoint f500f7e8, Name=Faizel Sunderji, Extn=631, Phys Extn=631
709575995mS CMTARGET: 0.16212.0 3485 Faizel Sunderji.0: ADD TWINNED PRIMARY twinbusy=0
709575995mS CMCallEvt: 0.16213.0 -1 BaseEP: NEW CMEndpoint f4fc9a20 TOTAL NOW=4 CALL_LIST=1
709575995mS CMCallEvt: CREATE CALL:3486 (f4f8d910)
709575996mS CMCallEvt: 0.16214.0 -1 BaseEP: NEW CMEndpoint f4fc8554 TOTAL NOW=5 CALL_LIST=1
709575997mS CMCallEvt: 0.16213.0 3486 GHOST:: StateChange: END=A CMCSIdle->CMCSDialInitiated
709575997mS CMTARGET: 0.16213.0 3486 GHOST:: LOOKUP CALL ROUTE: type=100 called_party=94036800910 sub= calling=92504348484 dir=out complete=1 ses=0
709575998mS CMTARGET: 0.16213.0 3486 GHOST:: ADD TARGET (N): number=94036800910 type=100 depth=1 nobar=1 setorig=1 ses=0
709575998mS CMTARGET: 0.16213.0 3486 GHOST:: SYS SC: 94036800910 2 4036800910 sc=type=Dial code=9N, num=N callinfop->sending_complete=1 secondary_dialtone=
709575998mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
709575999mS CMARS: MakeCallTarget - Called Number: 4036800910
709575999mS CMCallEvt: 0.16215.0 -1 BaseEP: NEW CMEndpoint f4fb5a58 TOTAL NOW=6 CALL_LIST=2
709575999mS CMTARGET: 0.16213.0 3486 GHOST:: INITIAL TARGETING SUCCEEDED
709575999mS CMTARGET: 0.16213.0 3486 GHOST:: GetNoAnswerTimer:15
709576000mS CMCallEvt: 0.16213.0 3486 GHOST:: StateChange: END=A CMCSDialInitiated->CMCSDialled
709576000mS CMCallEvt: 0.16214.0 3486 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
709576001mS CMCallEvt: 0.16215.0 3486 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
709576001mS CMCallEvt: 0.16214.0 3486 TargetingEP: RequestEnd 0.16215.0 3486 ARS for Main
709576002mS CMTARGET: 0.16213.0 3486 GHOST:: CancelTimer CMTCNoAnswerTimeout
709576002mS CMCallEvt: 0.16215.0 3486 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
709576002mS CMARS: FORM: Main - Received Number: 4036800910
709576003mS CMARS: FOUND A SHORT CODE - short_code: XN; - Tel: N - Called_Party: 4036800910 - Line Group Id: 51
709576003mS CMARS: FindActiveARSByGroupID GroupID=51 - Found
709576003mS CMARS: MakeCallTarget - Called Number: 4036800910
709576003mS CMCallEvt: 0.16216.0 -1 BaseEP: NEW CMEndpoint f505af94 TOTAL NOW=7 CALL_LIST=2
709576003mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 51 - Called Number: 4036800910 - Calling Number: 92504348484
709576003mS CMARS: SEND Setup TO LINE
709576004mS CMCallEvt: 0.16216.0 3486 ARS for Primary: StateChange: END=child CMCSIdle->CMCSOffering
709576004mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
709576004mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
709576004mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.16215.0 3486 ARS for Main - Call State: CMCSOverlapRecv
709576005mS CMCallEvt: 0.16215.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
709576005mS CMCallEvt: 0.16216.0 3486 ARS for Primary: StateChange: END=B CMCSOffering->CMCSOverlapRecv
709576005mS CMARS: FORM: Primary - Received Number: 4036800910
709576005mS CMARS: FOUND A SHORT CODE - short_code: XN; - Tel: N - Called_Party: 4036800910 - Line Group Id: 0
709576006mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
709576006mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
709576006mS CMCallEvt: 0.16217.0 -1 BaseEP: NEW CMEndpoint f4fa1180 TOTAL NOW=8 CALL_LIST=2
709576007mS CMTARGET: ISDN BChannel 23: in-service check = 1
709576007mS CMTARGET: ISDN BChannel 23: in-service check = 1
709576008mS CMARS: FOUND LINE - Line Id: 13 - using line group id: 0 - Called Number: 4036800910 - Calling Number: 92504348484
709576008mS CMARS: SEND Setup TO LINE
709576008mS CMCallEvt: 0.16217.0 3486 Q931 Trunk:13 CHAN=23: StateChange: END=child CMCSIdle->CMCSOffering
709576008mS CMCallEvt: 0.16215.0 -1 BaseEP: DELETE CMEndpoint f4fb5a58 TOTAL NOW=7 CALL_LIST=2
709576009mS CMCallEvt: 0.16214.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
709576009mS CMCallEvt: 0.16214.0 -1 BaseEP: DELETE CMEndpoint f4fc8554 TOTAL NOW=6 CALL_LIST=2
709576009mS CMExtnEvt: Faizel Sunderji:631 ExtnFault now 0
709576011mS CMCallEvt: 0.16218.0 -1 BaseEP: NEW CMEndpoint f5022c28 TOTAL NOW=7 CALL_LIST=2
709576011mS CMCallEvt: 0.16218.0 -1 Faizel Sunderji.-1: NEW CMExtnEndpoint f5022c28, Name=Faizel Sunderji, Extn=631, Phys Extn=
709576012mS CMTARGET: 0.16218.0 3485 Faizel Sunderji.0: ADD PRIMARY
709576012mS CMTARGET: 0.16218.0 3485 Faizel Sunderji.0: ADD FNE TWINNED MOBILE: Faizel Sunderji
709576012mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: INITIAL TARGETING SUCCEEDED
709576012mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: GetNoAnswerTimer:30
709576012mS CMCallEvt: 13.8.1 3485 Q931 Trunk:13 CHAN=4: StateChange: END=A CMCSDialInitiated->CMCSDialled
709576013mS CMLineTx: v=13
CMProceeding
Line: type=Q931Line 13 Call: lid=13 id=8 in=1
BChan: slot=0 chan=4
709576013mS CMCallEvt: 0.16211.0 3485 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
709576014mS CMCallEvt: 0.16212.0 3485 Faizel Sunderji.0: StateChange: END=T CMCSIdle->CMCSOffering
709576015mS CMExtnEvt: Faizel Sunderji: CMExtnHandler::SetCurrent( id: 0->16212 )
709576015mS CMExtnTx: v=631, p1=0
CMSetup
Line: type=DigitalExtn 6 Call: lid=0 id=16212 in=0
Called[631] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[92504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=iTel Networks
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=iTel Networks
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:0 R:4) number=92504348484
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=13 channel=4 rx_gain=32 tx_gain=32 ep_callid=8 ipaddr=10.4.247.160 apps=0
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Faizel Sunderji
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Faizel Sunderji
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=4032791631
Display [iTel Networks>Faizel Sunderji]
Timed: 03/11/21 14:33
Locale: enu
709576015mS CMExtnRx: v=631, p1=0
CMAlerting
Line: type=DigitalExtn 6 Call: lid=0 id=16212 in=0
709576015mS CMCallEvt: 0.16212.0 3485 Faizel Sunderji.0: StateChange: END=T CMCSOffering->CMCSRinging
709576016mS CMExtnEvt: v=8 State, new=Ringing old=Idle,0,0,Faizel Sunderji
709576016mS CMCallEvt: 0.16211.0 3485 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
709576017mS CMCallEvt: 13.8.1 3485 Q931 Trunk:13 CHAN=4: StateChange: END=A CMCSDialled->CMCSRingBack
709576017mS CMLineTx: v=13
CMAlerting
Line: type=Q931Line 13 Call: lid=13 id=8 in=1
BChan: slot=0 chan=4
709576018mS CMCallEvt: 0.16218.0 3485 Faizel Sunderji.0: StateChange: END=T CMCSIdle->CMCSOffering
709576018mS CMExtnEvt: Faizel Sunderji: CMExtnHandler::SetCurrent( id: 0->16218 )
709576019mS CMExtnTx: v=631, p1=0
CMSetup
Line: type=NoLine 0 Call: lid=0 id=16218 in=0
Called[631] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[92504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Faizel Sunderji
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Faizel Sunderji
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=iTel Networks
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=4032791631
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=iTel Networks
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:0 R:4) number=92504348484
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=13 channel=4 rx_gain=32 tx_gain=32 ep_callid=8 ipaddr=10.4.247.160 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
Display [iTel Networks>Faizel Sunderji]
Timed: 03/11/21 14:33
Locale: enu
709576019mS CMExtnRx: v=631, p1=0
CMAlerting
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
709576019mS CMCallEvt: 0.16218.0 3485 Faizel Sunderji.0: StateChange: END=T CMCSOffering->CMCSRinging
709576019mS CMExtnEvt: v=-1 State, new=Ringing old=Idle,0,0,Faizel Sunderji
709576020mS CD: CALL: 13.8.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 13" (7.5) Bend="Faizel Sunderji(631)" [Faizel Sunderji(631),Faizel Sunderji(631)] (0.0) CalledNum=631 (Faizel Sunderji) CallingNum=92504348484 (iTel Networks) Internal=0 Time=30 ASta
e=Ringin
709576021mS CMMap: a=7.5 b=0.0 R1
709576022mS ISDNL3Evt: v=13 stacknum=13 State, new=ICProceeding, old=Present id=8
709576023mS ISDNL3Evt: v=13 stacknum=13 State, new=Received, old=ICProceeding id=8
709576204mS RES: Wed 3/11/2021 14:33:03 FreeMem=59090952(1) CMMsg=7 (8) Buff=5200 960 1000 7423 5 Links=607 CPU=32/38/2411/20012/23841/0
709576204mS RES2: IP 500 V2 8.1(43) Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=65 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=17 TAPI=1 ASC=1 SYS=MNTD OPT=MNTD SDSPD=2034
709580008mS CMCallEvt: 0.16217.0 3486 Q931 Trunk:13 CHAN=23: Twin Mobile Delay Timer Expired - Initiate call to Mobile
709580008mS PRN: Setting configured voice gain for ch 23.
709580008mS CMLineTx: v=13
CMSetup
Line: type=Q931Line 13 Call: lid=0 id=16217 in=0
Called[4036800910] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[2504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=23
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Faizel Sunderji
709580009mS ISDNL3Evt: v=13 stacknum=13 State, new=Initiated, old=NullState id=16217
709580049mS ISDNL3Evt: v=13 stacknum=13 State, new=Proceeding, old=Initiated id=16217
709580050mS CMLineRx: v=13
CMProceeding
Line: type=Q931Line 13 Call: lid=0 id=16217 in=0
BChan: slot=0 chan=23
709580050mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
709580050mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
709580050mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.16216.0 3486 ARS for Primary - Call State: CMCSOverlapRecv
709580051mS CMCallEvt: 0.16216.0 -1 ARS for Primary: StateChange: END=X CMCSOverlapRecv->CMCSDelete
709580051mS CMCallEvt: 0.16217.0 3486 Q931 Trunk:13 CHAN=23: StateChange: END=B CMCSOffering->CMCSAccept
709580052mS CMCallEvt: 0.16216.0 -1 BaseEP: DELETE CMEndpoint f505af94 TOTAL NOW=6 CALL_LIST=2
709581331mS CMLineRx: v=13
CMProgress
Line: type=Q931Line 13 Call: lid=0 id=16217 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
709581331mS CMCallEvt: 0.16217.0 3486 Q931 Trunk:13 CHAN=23: StateChange: END=B CMCSAccept->CMCSRinging
709581332mS CMCallEvt: 0.16213.0 3486 GHOST:: StateChange: END=A CMCSDialled->CMCSRingBack
709586012mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: TimerExpired cause=CMTCCoverageTimeout
709586012mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: GetNoAnswerTimer:30
709592640mS ISDNL3Evt: v=13 stacknum=13 State, new=ReleaseReq, old=Received id=8
709592652mS ISDNL3Evt: v=13 stacknum=13 State, new=NullState, old=ReleaseReq id=8
709592653mS CMLineRx: v=13
CMReleaseComp
Line: type=Q931Line 13 Call: lid=13 id=8 in=1
BChan: slot=0 chan=4
Cause=16, Normal call clearing
709592653mS CMCallEvt: 13.8.1 3485 Q931 Trunk:13 CHAN=4: StateChange: END=A CMCSRingBack->CMCSCompleted
709592653mS CMTARGET: 13.8.1 3485 Q931 Trunk:13 CHAN=4: CancelTimer CMTCNoAnswerTimeout
709592655mS CMLOGGING: CALL:2021/11/0314:33,00:00:00,016,92504348484,I,631,4032791631,iTelNetworks,,,0,,""n/a,0
709592655mS CD: CALL: 13.8.1 BState=Ringing Cut=0 Music=0.0 Aend="Line 13" (7.5) Bend="Faizel Sunderji(631)" [Faizel Sunderji(631),Faizel Sunderji(631)] (0.0) CalledNum=631 (Faizel Sunderji) CallingNum=92504348484 (iTel Networks) Internal=0 Time=16665 A
tate=Idl
709592655mS CD: CALL: 13.8.1 Deleted
709592655mS CMCallEvt: 13.8.1 -1 Q931 Trunk:13 CHAN=4: StateChange: END=X CMCSCompleted->CMCSDelete
709592655mS CMCallEvt: 0.16211.0 -1 BaseEP: DELETE CMEndpoint f504a694 TOTAL NOW=5 CALL_LIST=1
709592656mS CMExtnEvt: Faizel Sunderji: CALL LOST (CMCauseNormal)
709592656mS CMExtnEvt: Faizel Sunderji: Extn(631) Calling Party Number(92504348484) Type(CMNTypeUnknown)
709592657mS CMCallEvt: 0.16212.0 -1 Faizel Sunderji.0: StateChange: END=X CMCSRinging->CMCSCompleted
709592657mS CMExtnEvt: v=8 State, new=PortRecoverDelay old=Ringing,0,0,Faizel Sunderji
709592657mS CMExtnTx: v=631, p1=0
CMReleaseComp
Line: type=DigitalExtn 6 Call: lid=0 id=16212 in=0
Called[631] Type=Unknown (0) Reason=CMDRdirect Calling[92504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
Cause=16, Normal call clearing
Timed: 03/11/21 14:33
709592657mS CMExtnEvt: Faizel Sunderji: CMExtnHandler::SetCurrent( id: 16212->0 )
709592658mS CMCallEvt: 0.16212.0 -1 Faizel Sunderji.-1: StateChange: END=X CMCSCompleted->CMCSDelete
709592658mS CMCallEvt: 0.16212.0 -1 BaseEP: DELETE CMEndpoint f500f7e8 TOTAL NOW=4 CALL_LIST=1
709592659mS CMExtnEvt: Faizel Sunderji: CALL LOST (CMCauseNormal)
709592659mS CMExtnEvt: Faizel Sunderji: Extn(631) Calling Party Number(92504348484) Type(CMNTypeUnknown)
709592659mS CMCallEvt: 0.16218.0 -1 Faizel Sunderji.0: StateChange: END=X CMCSRinging->CMCSCompleted
709592659mS CMExtnEvt: v=-1 State, new=PortRecoverDelay old=Ringing,0,0,Faizel Sunderji
709592660mS CMExtnTx: v=631, p1=0
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=16218 in=0
Called[631] Type=Unknown (0) Reason=CMDRdirect Calling[92504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
Cause=16, Normal call clearing
709592660mS CMExtnEvt: Faizel Sunderji: CMExtnHandler::SetCurrent( id: 16218->0 )
709592660mS CMCallEvt: 0.16218.0 -1 Faizel Sunderji.-1: StateChange: END=X CMCSCompleted->CMCSDelete
709592661mS CMCallEvt: 0.16213.0 3486 GHOST:: StateChange: END=A CMCSRingBack->CMCSCompleted
709592661mS CMLOGGING: CALL:2021/11/0314:33,00:00:00,000,92504348484,O,4036800910,94036800910,FaizelSunderji,,,0,,""n/a,0
709592662mS CMCallEvt: 0.16213.0 -1 GHOST:: CallLost (Ghost @) cause=CMCauseNormal
709592662mS CMCallEvt: 0.16213.0 -1 GHOST:: StateChange: END=X CMCSCompleted->CMCSDelete
709592662mS CMLineTx: v=13
CMDisconnect
Line: type=Q931Line 13 Call: lid=0 id=16217 in=0
Cause=16, Normal call clearing
709592663mS CMCallEvt: 0.16217.0 -1 Q931 Trunk:13 CHAN=23: StateChange: END=X CMCSRinging->CMCSCompleted
709592663mS CMCallEvt: END CALL:3486 (f4f8d910)
709592664mS CMTARGET: 0.16213.0 -1 BaseEP: ~CMTargetHandler f50483c4 ep f4fc9a20
709592664mS CMCallEvt: 0.16213.0 -1 BaseEP: DELETE CMEndpoint f4fc9a20 TOTAL NOW=3 CALL_LIST=0
709592664mS CMCallEvt: 0.16218.0 -1 BaseEP: DELETE CMEndpoint f5022c28 TOTAL NOW=2 CALL_LIST=0
709592664mS CMCallEvt: END CALL:3485 (f58429bc)
709592665mS CMTARGET: ISDN BChannel 4: in-service check = 1
709592665mS CMTARGET: ISDN BChannel 4: in-service check = 1
709592665mS CMTARGET: 13.8.1 -1 BaseEP: ~CMTargetHandler f4fd8c04 ep f5038c68
709592665mS CMCallEvt: 13.8.1 -1 BaseEP: DELETE CMEndpoint f5038c68 TOTAL NOW=1 CALL_LIST=0
709592666mS PRN: Config Write Wake Up
709592667mS ISDNL3Evt: v=13 stacknum=13 State, new=DiscReq, old=Proceeding id=16217
709592667mS CMMap: a=7.5 b=0.0 R0
709592668mS CMExtnEvt: Faizel Sunderji: Recover Timer reason=CMTRWrapUp
709592668mS CMExtnEvt: v=8 State, new=Idle old=PortRecoverDelay,0,0,Faizel Sunderji
709592670mS CMExtnEvt: Faizel Sunderji: Recover Timer reason=CMTRWrapUp
709592670mS CMExtnEvt: v=-1 State, new=Idle old=PortRecoverDelay,0,0,Faizel Sunderji
709592683mS ISDNL3Evt: v=13 stacknum=13 State, new=NullState, old=DiscReq id=16217
709592683mS CMLineRx: v=13
CMReleaseComp
Line: type=Q931Line 13 Call: lid=0 id=16217 in=0
BChan: slot=0 chan=23
709592684mS CMCallEvt: 0.16217.0 -1 Q931 Trunk:13 CHAN=23: StateChange: END=X CMCSCompleted->CMCSDelete
709592684mS CMTARGET: ISDN BChannel 23: in-service check = 1
709592684mS CMTARGET: ISDN BChannel 23: in-service check = 1
709592684mS CMCallEvt: 0.16217.0 -1 BaseEP: DELETE CMEndpoint f4fa1180 TOTAL NOW=0 CALL_LIST=0
709592712mS RES: Wed 3/11/2021 14:33:20 FreeMem=59166644(1) CMMsg=7 (8) Buff=5200 960 999 7423 5 Links=626 CPU=1/4/2411/20914/23841/0
709592712mS RES2: IP 500 V2 8.1(43) Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=66 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=17 TAPI=1 ASC=1 SYS=MNTD OPT=MNTD SDSPD=2034
709592763mS CMExtnEvt: Faizel Sunderji:631 ExtnFault now 1
709593166mS PRN: Updates IO list size 1 updated list size 1
709593167mS PRN: Sending Updates out to f50addd8 started
709593168mS PRN: Sending Updates out to f50addd8 finished
709593168mS PRN: Config Write Completed

********** SysMonitor v10.1 (43) [connected to 10.4.247.160 (DynastyCalgary)] **********
709596324mS PRN: Monitor Status IP 500 V2 8.1(43)
709596324mS PRN: LAW=U PRI=1, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=1 LANM=0 CkSRC=13 VMAIL=1(VER=3 TYP=1) 1-X=1 CALLS=0(TOT=3486)

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

critchey · Nov 3, 2021

Oh OK ya I missed that it was presented to the IPO as PRI.

Try this:
Create a new shortcode to dial out with, assuming you use 9N to dial out, create an 8N shortcode.

8N
Dial
NSS (SS = Pass Through Calling Number Pass through the Calling Party Number.)
50 Main (provided this is the ARS you use normally)

Then when you twin use this newly created shortcode instead of the normal shortcode. So if you normally use 9 to dial out, and you created the 8N, enter the number for twinning as 8+number
818005551212

The truth is just an excuse for lack of imagination.

Hostilis956 · Nov 3, 2021

Have tried that already earlier today without success.
The caller ID number always passes through without issue but the number does not.
The only part that is infuriating me is that the customer had a similar setup with an Adtran TA908 as the SIP/PRI gateway and apparently it worked without issue, but since moving to us and us putting in our Adtran TA904, it is not working.
Adtran hasn't been helpful as they've noted that the caller ID name is incorrect when it reaches the Adtran, and packet captures support this, so I have no idea and I am very sure the issue is with the PBX config somehow.

IPGuru · Nov 4, 2021

That current monitor trace is completly usless for diagnosing the issue you are reporting.

The 2st step in running am monitor trace is enabling the correct features

you need to enable ISDN L3 TX & RX so you can see what is actualy being sent to line

Do things on the cheap & it will cost you dear

Avaya Remote Support Engineer (A.R.S.E)

derfloh · Nov 4, 2021

And you have to tell us the expected data.

IP Office remote service
IP Office certificate check
CLI based call blocking
SCN fallback over PSTN

Hostilis956 · Nov 5, 2021

Ok certainly, below is the monitor trace with the ISDN L3 Transmit and Receive filter enabled.

What is happening is my number, 2504848484, is calling 4032791631 which is twinned to the external number 4036800910.
The call to the desk phone before it is twinned successfully shows my number and caller ID name "iTel Networks".
I am expecting this on the twinnined call but that is not what I am seeing, instead I am seeing "Faizel Sunderji" but with my number 2504848484>
This can be seen in the ISDNL3Tx at 867314116mS, where it shows "Faizel Sunderji" in the Display header.

Thanks again for everyone's help on this issue.

Monitor Trace:
UNICODE-UTF8
enu
867310092mS ISDNL3Rx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0199(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 80 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 16 .....
InformationElement = FAC
0000 1c 1b 9f 8b 01 00 a1 15 02 01 01 02 01 00 80 0d ................
0010 69 54 65 6c 20 4e 65 74 77 6f 72 6b 73 iTel Networks
InformationElement = CallingPartyNumber
0000 6c 0c 00 80 32 35 30 34 33 34 38 34 38 34 l...2504348484
InformationElement = CalledPartyNumber
0000 70 0b 80 34 30 33 32 37 39 31 36 33 31 p..4032791631
867310093mS CMTARGET: ISDN BChannel 22: in-service check = 1
867310093mS ISDNL3Evt: v=13 stacknum=13 State, new=Present, old=NullState id=9
867310094mS CMLineRx: v=13
CMSetup
Line: type=Q931Line 13 Call: lid=13 id=9 in=1
Called[4032791631] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[92504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=22
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 1
user 'iTel Networks' presentation Allowed
867310094mS PRN: Q931Trunk: Found QBChannel to match 0.22 --> 7.23
867310095mS CMCallEvt: 0.19424.0 -1 BaseEP: NEW CMEndpoint f5043744 TOTAL NOW=1 CALL_LIST=0
867310095mS CMTARGET: ISDN BChannel 22: in-service check = 1
867310095mS CMTARGET: ISDN BChannel 22: in-service check = 1
867310095mS CMCallEvt: CREATE CALL:4123 (f4fa3234)
867310096mS CMCallEvt: 0.19425.0 -1 BaseEP: NEW CMEndpoint f504a694 TOTAL NOW=2 CALL_LIST=0
867310097mS CD: CALL: 13.9.1 BState=Idle Cut=1 Music=0.0 Aend="Line 13" (7.23) Bend="" [] (0.0) CalledNum=4032791631 () CallingNum=92504348484 (iTel Networks) Internal=0 Time=2 AState=Idle
867310098mS CMCallEvt: 13.9.1 4123 Q931 Trunk:13 CHAN=22: StateChange: END=A CMCSIdle->CMCSDialInitiated
867310098mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: LOOKUP CALL ROUTE: type=0 called_party=4032791631 sub= calling=92504348484 dir=in complete=1 ses=0
867310098mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: SET BESTMATCH: length 10 vs -1 match=4032791631 dest=631
867310098mS CMCallEvt: Priority hike: call 4123 priority 0->1
867310098mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: LOOKUP ICR: DDI= CGPN=92504348484 (Destination 631 ) => CDPN=631
867310099mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: ADD TARGET (N): number=631 type=0 depth=1 nobar=1 setorig=1 ses=0
867310099mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: SET USER: Faizel Sunderji orig=1
867310099mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: ADD USER: Faizel Sunderji depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeUnknown) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
867310099mS CMCallEvt: 0.19426.0 -1 BaseEP: NEW CMEndpoint f502461c TOTAL NOW=3 CALL_LIST=1
867310099mS CMCallEvt: 0.19426.0 -1 Faizel Sunderji.-1: NEW CMExtnEndpoint f502461c, Name=Faizel Sunderji, Extn=631, Phys Extn=631
867310100mS CMTARGET: 0.19426.0 4123 Faizel Sunderji.0: ADD TWINNED PRIMARY twinbusy=0
867310101mS CMCallEvt: 0.19427.0 -1 BaseEP: NEW CMEndpoint f504c04c TOTAL NOW=4 CALL_LIST=1
867310101mS CMCallEvt: CREATE CALL:4124 (f4f8d910)
867310101mS CMCallEvt: 0.19428.0 -1 BaseEP: NEW CMEndpoint f4fc8554 TOTAL NOW=5 CALL_LIST=1
867310103mS CMCallEvt: 0.19427.0 4124 GHOST:: StateChange: END=A CMCSIdle->CMCSDialInitiated
867310103mS CMTARGET: 0.19427.0 4124 GHOST:: LOOKUP CALL ROUTE: type=100 called_party=94036800910 sub= calling=92504348484 dir=out complete=1 ses=0
867310103mS CMTARGET: 0.19427.0 4124 GHOST:: ADD TARGET (N): number=94036800910 type=100 depth=1 nobar=1 setorig=1 ses=0
867310103mS CMTARGET: 0.19427.0 4124 GHOST:: SYS SC: 94036800910 2 4036800910 sc=type=Dial code=9N, num=NSS callinfop->sending_complete=1 secondary_dialtone=
867310104mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
867310104mS CMARS: MakeCallTarget - Called Number: 4036800910
867310104mS CMCallEvt: 0.19429.0 -1 BaseEP: NEW CMEndpoint f505af94 TOTAL NOW=6 CALL_LIST=2
867310105mS CMTARGET: 0.19427.0 4124 GHOST:: INITIAL TARGETING SUCCEEDED
867310105mS CMTARGET: 0.19427.0 4124 GHOST:: GetNoAnswerTimer:15
867310105mS CMCallEvt: 0.19427.0 4124 GHOST:: StateChange: END=A CMCSDialInitiated->CMCSDialled
867310106mS CMCallEvt: 0.19428.0 4124 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
867310107mS CMCallEvt: 0.19429.0 4124 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
867310107mS CMCallEvt: 0.19428.0 4124 TargetingEP: RequestEnd 0.19429.0 4124 ARS for Main
867310107mS CMTARGET: 0.19427.0 4124 GHOST:: CancelTimer CMTCNoAnswerTimeout
867310108mS CMCallEvt: 0.19429.0 4124 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
867310108mS CMARS: FORM: Main - Received Number: 4036800910
867310108mS CMARS: FOUND A SHORT CODE - short_code: XN; - Tel: N - Called_Party: 4036800910 - Line Group Id: 51
867310108mS CMARS: FindActiveARSByGroupID GroupID=51 - Found
867310109mS CMARS: MakeCallTarget - Called Number: 4036800910
867310109mS CMCallEvt: 0.19430.0 -1 BaseEP: NEW CMEndpoint f505e410 TOTAL NOW=7 CALL_LIST=2
867310109mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 51 - Called Number: 4036800910 - Calling Number: 92504348484
867310109mS CMARS: SEND Setup TO LINE
867310110mS CMCallEvt: 0.19430.0 4124 ARS for Primary: StateChange: END=child CMCSIdle->CMCSOffering
867310110mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
867310110mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
867310110mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.19429.0 4124 ARS for Main - Call State: CMCSOverlapRecv
867310110mS CMCallEvt: 0.19429.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
867310111mS CMCallEvt: 0.19430.0 4124 ARS for Primary: StateChange: END=B CMCSOffering->CMCSOverlapRecv
867310111mS CMARS: FORM: Primary - Received Number: 4036800910
867310111mS CMARS: FOUND A SHORT CODE - short_code: XN; - Tel: N - Called_Party: 4036800910 - Line Group Id: 0
867310111mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
867310112mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
867310112mS CMCallEvt: 0.19431.0 -1 BaseEP: NEW CMEndpoint f503a568 TOTAL NOW=8 CALL_LIST=2
867310112mS CMTARGET: ISDN BChannel 23: in-service check = 1
867310113mS CMTARGET: ISDN BChannel 23: in-service check = 1
867310114mS CMARS: FOUND LINE - Line Id: 13 - using line group id: 0 - Called Number: 4036800910 - Calling Number: 92504348484
867310114mS CMARS: SEND Setup TO LINE
867310114mS CMCallEvt: 0.19431.0 4124 Q931 Trunk:13 CHAN=23: StateChange: END=child CMCSIdle->CMCSOffering
867310114mS CMCallEvt: 0.19429.0 -1 BaseEP: DELETE CMEndpoint f505af94 TOTAL NOW=7 CALL_LIST=2
867310115mS CMCallEvt: 0.19428.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
867310115mS CMCallEvt: 0.19428.0 -1 BaseEP: DELETE CMEndpoint f4fc8554 TOTAL NOW=6 CALL_LIST=2
867310115mS CMExtnEvt: Faizel Sunderji:631 ExtnFault now 0
867310117mS CMCallEvt: 0.19432.0 -1 BaseEP: NEW CMEndpoint f502f9a8 TOTAL NOW=7 CALL_LIST=2
867310117mS CMCallEvt: 0.19432.0 -1 Faizel Sunderji.-1: NEW CMExtnEndpoint f502f9a8, Name=Faizel Sunderji, Extn=631, Phys Extn=
867310118mS CMTARGET: 0.19432.0 4123 Faizel Sunderji.0: ADD PRIMARY
867310118mS CMTARGET: 0.19432.0 4123 Faizel Sunderji.0: ADD FNE TWINNED MOBILE: Faizel Sunderji
867310118mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: INITIAL TARGETING SUCCEEDED
867310118mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: GetNoAnswerTimer:30
867310119mS CMCallEvt: 13.9.1 4123 Q931 Trunk:13 CHAN=22: StateChange: END=A CMCSDialInitiated->CMCSDialled
867310119mS CMLineTx: v=13
CMProceeding
Line: type=Q931Line 13 Call: lid=13 id=9 in=1
BChan: slot=0 chan=22
867310119mS CMCallEvt: 0.19425.0 4123 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
867310120mS CMCallEvt: 0.19426.0 4123 Faizel Sunderji.0: StateChange: END=T CMCSIdle->CMCSOffering
867310121mS CMExtnEvt: Faizel Sunderji: CMExtnHandler::SetCurrent( id: 0->19426 )
867310121mS CMExtnTx: v=631, p1=0
CMSetup
Line: type=DigitalExtn 6 Call: lid=0 id=19426 in=0
Called[631] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[92504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 1
user 'iTel Networks' presentation Allowed
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:0 R:4) number=92504348484
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=13 channel=22 rx_gain=32 tx_gain=32 ep_callid=9 ipaddr=10.4.247.160 apps=0
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=iTel Networks
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=iTel Networks
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Faizel Sunderji
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Faizel Sunderji
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=4032791631
Display [iTel Networks>Faizel Sunderji]
Timed: 05/11/21 10:21
Locale: enu
867310122mS CMExtnRx: v=631, p1=0
CMAlerting
Line: type=DigitalExtn 6 Call: lid=0 id=19426 in=0
867310122mS CMCallEvt: 0.19426.0 4123 Faizel Sunderji.0: StateChange: END=T CMCSOffering->CMCSRinging
867310122mS CMExtnEvt: v=8 State, new=Ringing old=Idle,0,0,Faizel Sunderji
867310123mS CMCallEvt: 0.19425.0 4123 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
867310123mS CMCallEvt: 13.9.1 4123 Q931 Trunk:13 CHAN=22: StateChange: END=A CMCSDialled->CMCSRingBack
867310124mS CMLineTx: v=13
CMAlerting
Line: type=Q931Line 13 Call: lid=13 id=9 in=1
BChan: slot=0 chan=22
867310125mS CMCallEvt: 0.19432.0 4123 Faizel Sunderji.0: StateChange: END=T CMCSIdle->CMCSOffering
867310125mS CMExtnEvt: Faizel Sunderji: CMExtnHandler::SetCurrent( id: 0->19432 )
867310125mS CMExtnTx: v=631, p1=0
CMSetup
Line: type=NoLine 0 Call: lid=0 id=19432 in=0
Called[631] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[92504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Faizel Sunderji
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Faizel Sunderji
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=iTel Networks
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=4032791631
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=iTel Networks
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:0 R:4) number=92504348484
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=13 channel=22 rx_gain=32 tx_gain=32 ep_callid=9 ipaddr=10.4.247.160 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
Display [iTel Networks>Faizel Sunderji]
Timed: 05/11/21 10:21
Locale: enu
867310125mS CMExtnRx: v=631, p1=0
CMAlerting
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
867310126mS CMCallEvt: 0.19432.0 4123 Faizel Sunderji.0: StateChange: END=T CMCSOffering->CMCSRinging
867310126mS CMExtnEvt: v=-1 State, new=Ringing old=Idle,0,0,Faizel Sunderji
867310127mS CD: CALL: 13.9.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 13" (7.23) Bend="Faizel Sunderji(631)" [Faizel Sunderji(631),Faizel Sunderji(631)] (0.0) CalledNum=631 (Faizel Sunderji) CallingNum=92504348484 (iTel Networks) Internal=0 Time=32 ASt
te=Ringin
867310128mS CMMap: a=7.23 b=0.0 R1
867310128mS ISDNL3Evt: v=13 stacknum=13 State, new=ICProceeding, old=Present id=9
867310128mS ISDNL3Tx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0199(Local)
Message Type = CallProceeding
InformationElement = CHI
0000 18 03 a9 83 96 .....
867310129mS ISDNL3Evt: v=13 stacknum=13 State, new=Received, old=ICProceeding id=9
867310129mS ISDNL3Tx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0199(Local)
Message Type = Alerting
867310129mS RES: Fri 5/11/2021 10:21:44 FreeMem=59052860(2) CMMsg=7 (8) Buff=5200 958 999 7423 5 Links=484 CPU=34/3/2411/20826/23841/0
867310130mS RES2: IP 500 V2 8.1(43) Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=63 Poll=0 Ready=0 CMReady=2 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=17 TAPI=1 ASC=1 SYS=MNTD OPT=MNTD SDSPD=2034
867314114mS CMCallEvt: 0.19431.0 4124 Q931 Trunk:13 CHAN=23: Twin Mobile Delay Timer Expired - Initiate call to Mobile
867314114mS PRN: Setting configured voice gain for ch 23.
867314114mS CMLineTx: v=13
CMSetup
Line: type=Q931Line 13 Call: lid=0 id=19431 in=0
Called[4036800910] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[2504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=23
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Faizel Sunderji
867314115mS ISDNL3Evt: v=13 stacknum=13 State, new=Initiated, old=NullState id=19431
867314116mS ISDNL3Tx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0046(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 90 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 97 .....
InformationElement = Display
0000 28 10 b1 46 61 69 7a 65 6c 20 53 75 6e 64 65 72 (..Faizel Sunder
0010 6a 69 ji
InformationElement = CallingPartyNumber
0000 6c 0c 00 80 32 35 30 34 33 34 38 34 38 34 l...2504348484
InformationElement = CalledPartyNumber
0000 70 0b 80 34 30 33 36 38 30 30 39 31 30 p..4036800910
InformationElement = Sending Complete
867314155mS ISDNL3Rx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0046(Local)
Message Type = CallProceeding
InformationElement = CHI
0000 18 03 a9 83 17 .....
867314155mS ISDNL3Evt: v=13 stacknum=13 State, new=Proceeding, old=Initiated id=19431
867314156mS CMLineRx: v=13
CMProceeding
Line: type=Q931Line 13 Call: lid=0 id=19431 in=0
BChan: slot=0 chan=23
867314156mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
867314156mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
867314156mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.19430.0 4124 ARS for Primary - Call State: CMCSOverlapRecv
867314157mS CMCallEvt: 0.19430.0 -1 ARS for Primary: StateChange: END=X CMCSOverlapRecv->CMCSDelete
867314157mS CMCallEvt: 0.19431.0 4124 Q931 Trunk:13 CHAN=23: StateChange: END=B CMCSOffering->CMCSAccept
867314158mS CMCallEvt: 0.19430.0 -1 BaseEP: DELETE CMEndpoint f505e410 TOTAL NOW=6 CALL_LIST=2
867314299mS PRN: 610: Digit Pressed 6
867314664mS PRN: 610: Digit Pressed 2
867315114mS PRN: 610: Digit Pressed 8
867315130mS RES: Fri 5/11/2021 10:21:49 FreeMem=59054984(2) CMMsg=7 (8) Buff=5200 960 1000 7423 5 Links=499 CPU=3/7/2411/20823/23841/0
867315130mS RES2: IP 500 V2 8.1(43) Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=62 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=17 TAPI=1 ASC=1 SYS=MNTD OPT=MNTD SDSPD=2034
867315288mS ISDNL3Rx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0046(Local)
Message Type = Progress
InformationElement = PI
0000 1e 02 82 88 ....
867315289mS CMLineRx: v=13
CMProgress
Line: type=Q931Line 13 Call: lid=0 id=19431 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
867315289mS CMCallEvt: 0.19431.0 4124 Q931 Trunk:13 CHAN=23: StateChange: END=B CMCSAccept->CMCSRinging
867315290mS CMCallEvt: 0.19427.0 4124 GHOST:: StateChange: END=A CMCSDialled->CMCSRingBack
867315654mS CMExtnEvt: Bozica Baljak: ProcessShortCode feature=FollowMeTo id=628
867315655mS PRN: Config Write Wake Up
867316155mS PRN: Updates IO list size 1 updated list size 2
867316160mS PRN: Sending Updates out to f50addd8 started
867316161mS PRN: Sending Updates out to f50addd8 finished
867316162mS PRN: Config Write Completed
867320118mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: TimerExpired cause=CMTCCoverageTimeout
867320118mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: GetNoAnswerTimer:30
867323636mS RES: Fri 5/11/2021 10:21:57 FreeMem=59056452(2) CMMsg=7 (8) Buff=5200 960 1000 7423 5 Links=535 CPU=13/15/2411/20761/23841/0
867323636mS RES2: IP 500 V2 8.1(43) Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=60 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=17 TAPI=1 ASC=1 SYS=MNTD OPT=MNTD SDSPD=2034
867329241mS ISDNL3Rx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0199(Remote)
Message Type = Disconnect
InformationElement = CAUSE
0000 08 02 82 90 ....
867329241mS ISDNL3Evt: v=13 stacknum=13 State, new=ReleaseReq, old=Received id=9
867329241mS ISDNL3Tx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0199(Local)
Message Type = Release
867329254mS ISDNL3Rx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0199(Remote)
Message Type = ReleaseComplete
867329255mS ISDNL3Evt: v=13 stacknum=13 State, new=NullState, old=ReleaseReq id=9
867329255mS CMLineRx: v=13
CMReleaseComp
Line: type=Q931Line 13 Call: lid=13 id=9 in=1
BChan: slot=0 chan=22
Cause=16, Normal call clearing
867329256mS CMCallEvt: 13.9.1 4123 Q931 Trunk:13 CHAN=22: StateChange: END=A CMCSRingBack->CMCSCompleted
867329256mS CMTARGET: 13.9.1 4123 Q931 Trunk:13 CHAN=22: CancelTimer CMTCNoAnswerTimeout
867329257mS CMLOGGING: CALL:2021/11/0510:21,00:00:00,019,92504348484,I,631,4032791631,iTelNetworks,,,0,,""n/a,0
867329257mS CD: CALL: 13.9.1 BState=Ringing Cut=0 Music=0.0 Aend="Line 13" (7.23) Bend="Faizel Sunderji(631)" [Faizel Sunderji(631),Faizel Sunderji(631)] (0.0) CalledNum=631 (Faizel Sunderji) CallingNum=92504348484 (iTel Networks) Internal=0 Time=19162
State=Idl
867329257mS CD: CALL: 13.9.1 Deleted
867329257mS CMCallEvt: 13.9.1 -1 Q931 Trunk:13 CHAN=22: StateChange: END=X CMCSCompleted->CMCSDelete
867329258mS CMCallEvt: 0.19425.0 -1 BaseEP: DELETE CMEndpoint f504a694 TOTAL NOW=5 CALL_LIST=1
867329258mS CMExtnEvt: Faizel Sunderji: CALL LOST (CMCauseNormal)
867329258mS CMExtnEvt: Faizel Sunderji: Extn(631) Calling Party Number(92504348484) Type(CMNTypeUnknown)
867329259mS CMCallEvt: 0.19426.0 -1 Faizel Sunderji.0: StateChange: END=X CMCSRinging->CMCSCompleted
867329259mS CMExtnEvt: v=8 State, new=PortRecoverDelay old=Ringing,0,0,Faizel Sunderji
867329259mS CMExtnTx: v=631, p1=0
CMReleaseComp
Line: type=DigitalExtn 6 Call: lid=0 id=19426 in=0
Called[631] Type=Unknown (0) Reason=CMDRdirect Calling[92504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
Cause=16, Normal call clearing
Timed: 05/11/21 10:22
867329259mS CMExtnEvt: Faizel Sunderji: CMExtnHandler::SetCurrent( id: 19426->0 )
867329260mS CMCallEvt: 0.19426.0 -1 Faizel Sunderji.-1: StateChange: END=X CMCSCompleted->CMCSDelete
867329260mS CMCallEvt: 0.19426.0 -1 BaseEP: DELETE CMEndpoint f502461c TOTAL NOW=4 CALL_LIST=1
867329261mS CMExtnEvt: Faizel Sunderji: CALL LOST (CMCauseNormal)
867329261mS CMExtnEvt: Faizel Sunderji: Extn(631) Calling Party Number(92504348484) Type(CMNTypeUnknown)
867329261mS CMCallEvt: 0.19432.0 -1 Faizel Sunderji.0: StateChange: END=X CMCSRinging->CMCSCompleted
867329261mS CMExtnEvt: v=-1 State, new=PortRecoverDelay old=Ringing,0,0,Faizel Sunderji
867329262mS CMExtnTx: v=631, p1=0
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=19432 in=0
Called[631] Type=Unknown (0) Reason=CMDRdirect Calling[92504348484] Type=Unknown Plan=Unknown Pres=Allowed (0)
Cause=16, Normal call clearing
867329262mS CMExtnEvt: Faizel Sunderji: CMExtnHandler::SetCurrent( id: 19432->0 )
867329262mS CMCallEvt: 0.19432.0 -1 Faizel Sunderji.-1: StateChange: END=X CMCSCompleted->CMCSDelete
867329263mS CMCallEvt: 0.19427.0 4124 GHOST:: StateChange: END=A CMCSRingBack->CMCSCompleted
867329263mS CMLOGGING: CALL:2021/11/0510:21,00:00:00,000,92504348484,O,4036800910,94036800910,FaizelSunderji,,,0,,""n/a,0
867329264mS CMCallEvt: 0.19427.0 -1 GHOST:: CallLost (Ghost @) cause=CMCauseNormal
867329264mS CMCallEvt: 0.19427.0 -1 GHOST:: StateChange: END=X CMCSCompleted->CMCSDelete
867329265mS CMLineTx: v=13
CMDisconnect
Line: type=Q931Line 13 Call: lid=0 id=19431 in=0
Cause=16, Normal call clearing
867329265mS CMCallEvt: 0.19431.0 -1 Q931 Trunk:13 CHAN=23: StateChange: END=X CMCSRinging->CMCSCompleted
867329265mS CMCallEvt: END CALL:4124 (f4f8d910)
867329266mS CMTARGET: 0.19427.0 -1 BaseEP: ~CMTargetHandler f50483c4 ep f504c04c
867329266mS CMCallEvt: 0.19427.0 -1 BaseEP: DELETE CMEndpoint f504c04c TOTAL NOW=3 CALL_LIST=0
867329266mS CMCallEvt: 0.19432.0 -1 BaseEP: DELETE CMEndpoint f502f9a8 TOTAL NOW=2 CALL_LIST=0
867329266mS CMCallEvt: END CALL:4123 (f4fa3234)
867329267mS CMTARGET: ISDN BChannel 22: in-service check = 1
867329267mS CMTARGET: ISDN BChannel 22: in-service check = 1
867329267mS CMTARGET: 13.9.1 -1 BaseEP: ~CMTargetHandler f4fd8c04 ep f5043744
867329268mS CMCallEvt: 13.9.1 -1 BaseEP: DELETE CMEndpoint f5043744 TOTAL NOW=1 CALL_LIST=0
867329268mS CMMap: a=7.23 b=0.0 R0
867329269mS PRN: Config Write Wake Up
867329270mS ISDNL3Evt: v=13 stacknum=13 State, new=DiscReq, old=Proceeding id=19431
867329270mS ISDNL3Tx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0046(Remote)
Message Type = Disconnect
InformationElement = CAUSE
0000 08 02 80 90 ....
867329271mS CMExtnEvt: Faizel Sunderji: Recover Timer reason=CMTRWrapUp
867329271mS CMExtnEvt: v=8 State, new=Idle old=PortRecoverDelay,0,0,Faizel Sunderji
867329273mS CMExtnEvt: Faizel Sunderji: Recover Timer reason=CMTRWrapUp
867329273mS CMExtnEvt: v=-1 State, new=Idle old=PortRecoverDelay,0,0,Faizel Sunderji
867329284mS ISDNL3Rx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0046(Local)
Message Type = Release
867329285mS ISDNL3Tx: v=13 peb=13
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0046(Remote)
Message Type = ReleaseComplete
867329285mS ISDNL3Evt: v=13 stacknum=13 State, new=NullState, old=DiscReq id=19431
867329286mS CMLineRx: v=13
CMReleaseComp
Line: type=Q931Line 13 Call: lid=0 id=19431 in=0
BChan: slot=0 chan=23
867329286mS CMCallEvt: 0.19431.0 -1 Q931 Trunk:13 CHAN=23: StateChange: END=X CMCSCompleted->CMCSDelete
867329287mS CMTARGET: ISDN BChannel 23: in-service check = 1
867329287mS CMTARGET: ISDN BChannel 23: in-service check = 1
867329287mS CMCallEvt: 0.19431.0 -1 BaseEP: DELETE CMEndpoint f503a568 TOTAL NOW=0 CALL_LIST=0
867329366mS CMExtnEvt: Faizel Sunderji:631 ExtnFault now 1
867329636mS RES: Fri 5/11/2021 10:22:03 FreeMem=59128956(2) CMMsg=7 (8) Buff=5200 960 1000 7423 5 Links=554 CPU=1/3/2411/20989/23841/0
867329636mS RES2: IP 500 V2 8.1(43) Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=64 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=17 TAPI=1 ASC=1 SYS=MNTD OPT=MNTD SDSPD=2034
867329769mS PRN: Updates IO list size 1 updated list size 1
867329772mS PRN: Sending Updates out to f50addd8 started
867329772mS PRN: Sending Updates out to f50addd8 finished
867329772mS PRN: Config Write Completed

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

Tek-Tips is the largest IT community on the Internet today!

Members share and learn making Tek-Tips Forums the best source of peer-reviewed technical information on the Internet!

Avaya IP Office Caller ID Name Issue When Twinning

Hostilis956

ISP

critchey

IS-IT--Management

Hostilis956

ISP

Hostilis956

ISP

critchey

IS-IT--Management

Hostilis956

ISP

IPGuru

Vendor

derfloh

Technical User

Hostilis956

ISP

Similar threads

Part and Inventory Search

Sponsor