Hi all,
I'm having a battle with the line provider for one of our customers (Gamma). The customer wants to display the main DDI when dialling out but it keeps showing the circuit number. When I speak to Gamma they say the phone system is sending the circuit number when dialling out, but from the traces I have done, that is not the case. I was wondering if someone here could cast their eyes over the trace and see if I'm missing anything. The circuit number is 02074xxxxxx (which is not mentioned once in the trace) and the number we are trying to display is 02073xxxxxx.
Trace below.
Thanks for any help.
12:20:38 1803368667mS CMExtnEvt: v=3 State, new=Idle old=PortRecoverDelay,0,0,Aran
12:20:38 1803368668mS CMExtnTx: v=219, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 4 Call: lid=0 id=-1 in=0
Called[Aran Msgs=0 Old=2 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Aran Msgs=0]
Timed: 23/02/16 12:20
12:20:43 1803373015mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 1d ....
12:20:43 1803373015mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 1d ....
12:20:43 1803373015mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 73 ...s
12:20:43 1803373018mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 73 ...s
12:20:44 1803374744mS ISDNL1Rx: v=5 peb=5
0000 02 01 01 67 ...g
12:20:44 1803374744mS ISDNL2Rx: v=5 peb=5
0000 02 01 01 67 ...g
12:20:44 1803374744mS ISDNL2Tx: v=5 peb=5
0000 02 01 01 83 ....
12:20:44 1803374748mS ISDNL1Tx: v=5 peb=5
0000 02 01 01 83 ....
12:20:45 1803375352mS CMCallEvt: 0000000000000000 0.7719.0 -1 BaseEP: NEW CMEndpoint f1803234 TOTAL NOW=1 CALL_LIST=0
12:20:45 1803375352mS CMCallEvt: 0000000000000000 0.7719.0 -1 Kate.-1: NEW CMExtnEndpoint f1803234, Name=Kate, Extn=214, Phys Extn=214
12:20:45 1803375353mS CMCallEvt: CREATE CALL:1780 (f181d23c)
12:20:45 1803375353mS CMCallEvt: 0000000000000000 0.7720.0 -1 BaseEP: NEW CMEndpoint f181b948 TOTAL NOW=2 CALL_LIST=0
12:20:45 1803375355mS CMExtnEvt: Kate: CMExtnHandler::SetCurrent( id: 0->7719 )
12:20:45 1803375355mS CMExtnRx: v=214, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[214] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Kate
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Kate
12:20:45 1803375355mS CMCallEvt: c0a8fe0200001e27 0.7719.0 1780 Kate.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
12:20:45 1803375356mS CMExtnEvt: v=6 State, new=Dialling old=Idle,0,0,Kate
12:20:45 1803375356mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: LOOKUP CALL ROUTE: GID=0 type=100 called_party= sub= calling=214 dir=out complete=0 ses=0
12:20:45 1803375356mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
12:20:45 1803375357mS CMCallEvt: c0a8fe0200001e27 0.7719.0 1780 Kate.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
12:20:45 1803375357mS CMExtnTx: v=214, p1=0
CMSetupAck
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
BChan: slot=20 chan=6
Timed: 23/02/16 12:20
12:20:45 1803375358mS CD: CALL: 0.7719.0 BState=Idle Cut=1 Music=3.0 Aend="Kate(214)" (20.6) Bend="" [] (0.0) CalledNum= () CallingNum=214 (Kate) Internal=1 Time=6 AState=DialInitiated
12:20:45 1803375359mS CMMap: a=20.6 b=0.0 D1
12:20:45 1803375362mS RES: Tue 23/2/2016 12:20:45 FreeMem=56555732 (Heap=56154464(0) + Cache=401268) MemObjs=9239(Max 9646) CMMsg=4(4) Buff=5200 1338 1000 7427 5 Links=52399(58000) BTree=14535 CPU=04.94% CPUStats=04.23%/1/5/2568/12039/12448/00.03%/0/02.0
8% MCR=0 MCW=0
12:20:45 1803375362mS RES2: IP 500 V2 9.1.0.0 build 437 Tasks=52 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=68 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
12:20:45 1803375362mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
12:20:45 1803375363mS RES5: CLog MemObjs=428 FreePoolMem(Objs)=3744(72) TotalMem=129600
12:20:45 1803375632mS ISDNL1Rx: v=6 peb=6
0000 02 01 01 27 ...'
12:20:45 1803375632mS ISDNL2Rx: v=6 peb=6
0000 02 01 01 27 ...'
12:20:45 1803375632mS ISDNL2Tx: v=6 peb=6
0000 02 01 01 5b ...[
12:20:45 1803375636mS ISDNL1Tx: v=6 peb=6
0000 02 01 01 5b ...[
12:20:46 1803376336mS ISDNL1Rx: v=2 peb=2
0000 02 01 01 31 ...1
12:20:46 1803376336mS ISDNL2Rx: v=2 peb=2
0000 02 01 01 31 ...1
12:20:46 1803376336mS ISDNL2Tx: v=2 peb=2
0000 02 01 01 79 ...y
12:20:46 1803376340mS ISDNL1Tx: v=2 peb=2
0000 02 01 01 79 ...y
12:20:46 1803376727mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 0
12:20:46 1803376728mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
12:20:46 1803376729mS CMMap: a=20.6 b=0.0 D0
12:20:47 1803377076mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 2
12:20:47 1803377076mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
12:20:47 1803377339mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 0
12:20:47 1803377339mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
12:20:47 1803377564mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 8
12:20:47 1803377565mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[8] Type=Default (100) Reason=CMDRdirect
12:20:47 1803377565mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: CancelTimer CMTCDelayedProcessing
12:20:47 1803377565mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: LOOKUP CALL ROUTE: GID=0 type=100 called_party=0208 sub= calling=214 dir=out complete=0 ses=0
12:20:47 1803377565mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: ADD TARGET (N): number=0208 type=100 depth=1 nobar=1 setorig=1 ses=0
12:20:47 1803377566mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: DEF SC: 0208 0 sc=type=Dial code=?, num=.s02073xxxxxx
12:20:47 1803377566mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
12:20:47 1803377566mS CMARS: MakeCallTarget - Called Number: 0208
12:20:47 1803377566mS CMCallEvt: 0000000000000000 0.7721.0 -1 BaseEP: NEW CMEndpoint f181e934 TOTAL NOW=3 CALL_LIST=1
12:20:47 1803377567mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: CancelTimer CMTCDialToneTimeout
12:20:47 1803377567mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: INITIAL TARGETING SUCCEEDED
12:20:47 1803377567mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: GetNoAnswerTimer:15
12:20:47 1803377568mS CMCallEvt: 0000000000000000 0.7720.0 1780 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
12:20:47 1803377568mS CMCallEvt: 0000000000000000 0.7721.0 1780 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
12:20:47 1803377569mS CMCallEvt: 0000000000000000 0.7720.0 1780 TargetingEP: RequestEnd 0000000000000000 0.7721.0 1780 ARS for Main
12:20:47 1803377569mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: CancelTimer CMTCNoAnswerTimeout
12:20:47 1803377570mS CMCallEvt: 0000000000000000 0.7721.0 1780 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
12:20:47 1803377570mS CMARS: FORM: Main - Received Number: 0208
12:20:47 1803377570mS CMARS: FOUND A SHORT CODE - short_code: ? - Tel: .s02073xxxxxx - Called_Party: 0208 - Line Group Id: 0
12:20:47 1803377570mS CMARS: SetCurrentTarget: Short_Code: ? - Line_Group_ID: 0
12:20:47 1803377570mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
12:20:47 1803377570mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
12:20:47 1803377571mS CMCallEvt: 0000000000000000 0.7722.0 -1 BaseEP: NEW CMEndpoint f183e8dc TOTAL NOW=4 CALL_LIST=1
12:20:47 1803377571mS CMARS: ModifyCMARSTarget: Short_Code: ? - Line_Group_ID: 0 set line status to CMARS_TRYING
12:20:47 1803377572mS CMARS: FOUND LINE - Line Id: 6 - using line group id: 0 (code ?, line 0) - Called Number: 0208 - Calling Number: 02073xxxxxx
12:20:47 1803377572mS CMARS: SEND Setup TO LINE
12:20:47 1803377572mS CMCallEvt: c0a8fe0200001e2a 0.7722.0 1780 Q931 Trunk:6 CHAN=-1: StateChange: END=child CMCSIdle->CMCSOffering
12:20:47 1803377572mS CMLineTx: v=6
CMSetup
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[0208] Type=Unknown (0) Reason=CMDRdirect Calling[02073xxxxxx] Type=Unknown Plan=Unknown
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
Locale: eng
12:20:47 1803377572mS CMCallEvt: 0000000000000000 0.7720.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
12:20:47 1803377573mS CMCallEvt: 0000000000000000 0.7720.0 -1 BaseEP: DELETE CMEndpoint f181b948 TOTAL NOW=3 CALL_LIST=1
12:20:47 1803377573mS CD: CALL: 0.7719.0 BState=Idle Cut=2 Music=0.0 Aend="Kate(214)" (20.6) Bend="" [Line 6] (0.0) CalledNum=0208 () CallingNum=214 (Kate) Internal=1 Time=2221 AState=Dialling
12:20:47 1803377574mS ISDNL3Evt: v=6 stacknum=6 State, new=Initiated, old=NullState id=7722
12:20:47 1803377574mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 80 90 a3 .....
InformationElement = CallingPartyNumber
0000 6c 0d 00 80 30 32 30 37 33 32 33 33 33 35 30 l...02073xxxxxx
InformationElement = CalledPartyNumber
0000 70 05 80 30 32 30 38 p..0208
12:20:47 1803377574mS ISDNL2Tx: v=6 peb=6
0000 00 01 26 5a 08 01 4f 05 04 03 80 90 a3 6c 0d 00 ..&Z..O......l..
0010 80 30 32 30 37 33 32 33 33 33 35 30 70 05 80 30 .02073xxxxxxp..0
0020 32 30 38 208
12:20:47 1803377594mS ISDNL1Tx: v=6 peb=6
0000 00 01 26 5a 08 01 4f 05 04 03 80 90 a3 6c 0d 00 ..&Z..O......l..
0010 80 30 32 30 37 33 32 33 33 33 35 30 70 05 80 30 .02073xxxxxxp..0
0020 32 30 38 208
12:20:47 1803377602mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 28 ...(
12:20:47 1803377602mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 28 ...(
12:20:47 1803377741mS ISDNL1Rx: v=6 peb=6
0000 02 01 5a 28 08 01 cf 0d 18 01 89 ..Z(.......
12:20:47 1803377742mS ISDNL2Rx: v=6 peb=6
0000 02 01 5a 28 08 01 cf 0d 18 01 89 ..Z(.......
12:20:47 1803377742mS ISDNL3Rx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Local)
Message Type = SetupAck
InformationElement = CHI
0000 18 01 89 ...
12:20:47 1803377742mS ISDNL3Evt: v=6 stacknum=6 State, new=OverlapSend, old=Initiated id=7722
12:20:47 1803377742mS ISDNL2Tx: v=6 peb=6
0000 02 01 01 5c ... 12:20:47 1803377743mS CMLineRx: v=6
CMSetupAck
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
BChan: slot=0 chan=1
12:20:47 1803377744mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
12:20:47 1803377744mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
12:20:47 1803377744mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: c0a8fe0200001e27 0.7721.0 1780 ARS for Main - Call State: CMCSOverlapRecv
12:20:47 1803377744mS CMCallEvt: c0a8fe0200001e27 0.7721.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
12:20:47 1803377745mS CMCallEvt: c0a8fe0200001e2a 0.7722.0 1780 Q931 Trunk:6 CHAN=1: StateChange: END=B CMCSOffering->CMCSOverlapRecv
12:20:47 1803377745mS CMCallEvt: c0a8fe0200001e27 0.7721.0 -1 BaseEP: DELETE CMEndpoint f181e934 TOTAL NOW=2 CALL_LIST=1
12:20:47 1803377746mS CD: CALL: 0.7719.0 BState=Idle Cut=2 Music=0.0 Aend="Kate(214)" (20.6) Bend="Line 6" [Line 6] (3.5) CalledNum=0208 () CallingNum=214 (Kate) Internal=0 Time=2394 AState=Dialling
12:20:47 1803377746mS ISDNL1Tx: v=6 peb=6
0000 02 01 01 5c ... 12:20:47 1803377747mS CMMap: PCG::MapBChan pcp[86]b1r0 cp_b f524ab24 other_cp_b 0 type CGTypeSimple
12:20:47 1803377747mS CMMap: PCG::MapBChan pcp[73]b1r0 cp_b f567e7e0 other_cp_b f524ab24 type CGTypeSimple
12:20:47 1803377747mS CMMap: a=20.6 b=3.5 M12
12:20:47 1803377826mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 2
12:20:47 1803377826mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
12:20:47 1803377827mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:47 1803377828mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 32 p..2
12:20:47 1803377828mS ISDNL2Tx: v=6 peb=6
0000 00 01 28 5c 08 01 4f 7b 70 02 80 32 ..(\..O{p..2
12:20:47 1803377836mS ISDNL1Tx: v=6 peb=6
0000 00 01 28 5c 08 01 4f 7b 70 02 80 32 ..(\..O{p..2
12:20:47 1803377845mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 2a ...*
12:20:47 1803377845mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 2a ...*
12:20:48 1803378014mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 3
12:20:48 1803378015mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
12:20:48 1803378015mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:48 1803378016mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 33 p..3
12:20:48 1803378016mS ISDNL2Tx: v=6 peb=6
0000 00 01 2a 5c 08 01 4f 7b 70 02 80 33 ..*\..O{p..3
12:20:48 1803378024mS ISDNL1Tx: v=6 peb=6
0000 00 01 2a 5c 08 01 4f 7b 70 02 80 33 ..*\..O{p..3
12:20:48 1803378033mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 2c ...,
12:20:48 1803378033mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 2c ...,
12:20:48 1803378214mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 2
12:20:48 1803378214mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
12:20:48 1803378215mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:48 1803378216mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 32 p..2
12:20:48 1803378217mS ISDNL2Tx: v=6 peb=6
0000 00 01 2c 5c 08 01 4f 7b 70 02 80 32 ..,\..O{p..2
12:20:48 1803378224mS ISDNL1Tx: v=6 peb=6
0000 00 01 2c 5c 08 01 4f 7b 70 02 80 32 ..,\..O{p..2
12:20:48 1803378233mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 2e ....
12:20:48 1803378233mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 2e ....
12:20:48 1803378427mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 1
12:20:48 1803378427mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
12:20:48 1803378427mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:48 1803378429mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 31 p..1
12:20:48 1803378429mS ISDNL2Tx: v=6 peb=6
0000 00 01 2e 5c 08 01 4f 7b 70 02 80 31 ...\..O{p..1
12:20:48 1803378437mS ISDNL1Tx: v=6 peb=6
0000 00 01 2e 5c 08 01 4f 7b 70 02 80 31 ...\..O{p..1
12:20:48 1803378445mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 30 ...0
12:20:48 1803378446mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 30 ...0
12:20:49 1803379089mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 1
12:20:49 1803379089mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
12:20:49 1803379090mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:49 1803379091mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 31 p..1
12:20:49 1803379091mS ISDNL2Tx: v=6 peb=6
0000 00 01 30 5c 08 01 4f 7b 70 02 80 31 ..0\..O{p..1
12:20:49 1803379099mS ISDNL1Tx: v=6 peb=6
0000 00 01 30 5c 08 01 4f 7b 70 02 80 31 ..0\..O{p..1
12:20:49 1803379108mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 32 ...2
12:20:49 1803379108mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 32 ...2
12:20:49 1803379701mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 9
12:20:49 1803379702mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
12:20:49 1803379702mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:49 1803379704mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 39 p..9
12:20:49 1803379704mS ISDNL2Tx: v=6 peb=6
0000 00 01 32 5c 08 01 4f 7b 70 02 80 39 ..2\..O{p..9
12:20:49 1803379711mS ISDNL1Tx: v=6 peb=6
0000 00 01 32 5c 08 01 4f 7b 70 02 80 39 ..2\..O{p..9
12:20:49 1803379721mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 34 ...4
12:20:49 1803379721mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 34 ...4
12:20:49 1803379902mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 0
12:20:49 1803379902mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
12:20:49 1803379903mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:49 1803379904mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 30 p..0
12:20:49 1803379904mS ISDNL2Tx: v=6 peb=6
0000 00 01 34 5c 08 01 4f 7b 70 02 80 30 ..4\..O{p..0
12:20:49 1803379912mS ISDNL1Tx: v=6 peb=6
0000 00 01 34 5c 08 01 4f 7b 70 02 80 30 ..4\..O{p..0
12:20:49 1803379920mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 36 ...6
12:20:49 1803379920mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 36 ...6
12:20:51 1803381589mS ISDNL1Rx: v=6 peb=6
0000 02 01 5c 36 08 01 cf 02 1e 02 8a 81 ..\6........
12:20:51 1803381589mS ISDNL2Rx: v=6 peb=6
0000 02 01 5c 36 08 01 cf 02 1e 02 8a 81 ..\6........
12:20:51 1803381589mS ISDNL3Rx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Local)
Message Type = CallProceeding
InformationElement = PI
0000 1e 02 8a 81 ....
12:20:51 1803381589mS ISDNL3Evt: v=6 stacknum=6 State, new=Proceeding, old=OverlapSend id=7722
12:20:51 1803381590mS ISDNL2Tx: v=6 peb=6
0000 02 01 01 5e ...^
12:20:51 1803381590mS CMLineRx: v=6
CMProceeding
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLNetBeyondIWPoint (10), pd=CMPDNotISDN (1)
12:20:51 1803381590mS CMCallEvt: c0a8fe0200001e2a 0.7722.0 1780 Q931 Trunk:6 CHAN=1: StateChange: END=B CMCSOverlapRecv->CMCSAccept
12:20:51 1803381591mS CMCallEvt: c0a8fe0200001e27 0.7719.0 1780 Kate.0: StateChange: END=A CMCSDialling->CMCSDialled
12:20:51 1803381591mS CMExtnEvt: v=6 State, new=Proceeding old=Dialling,0,0,Kate
12:20:51 1803381591mS CMExtnTx: v=214, p1=0
CMProceeding
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLNetBeyondIWPoint (10), pd=CMPDNotISDN (1)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=02082xxxxxx
IE CMIEDeviceDetail (231) c0a8fe0200001e2a LOCALE=eng HW=15 VER=9 class=CMDeviceISDNTrunk type=0 number=6 channel=1 features=0x0 rx_gain=32 tx_gain=32 ep_callid=7722 ipaddr=192.168.254.2 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=1
Timed: 23/02/16 12:20
12:20:51 1803381592mS CD: CALL: 0.7719.0 BState=Ringing Cut=3 Music=0.0 Aend="Kate(214)" (20.6) Bend="Line 6" [Line 6] (3.5) CalledNum=02082xxxxxx () CallingNum=214 (Kate) Internal=0 Time=6240 AState=Dialled
12:20:51 1803381593mS ISDNL1Tx: v=6 peb=6
0000 02 01 01 5e ...^
12:20:51 1803381877mS RES: Tue 23/2/2016 12:20:51 FreeMem=56552400 (Heap=56154464(0) + Cache=397936) MemObjs=9253(Max 9646) CMMsg=4(4) Buff=5200 1340 1000 7426 5 Links=52452(58000) BTree=14534 CPU=05.26% CPUStats=05.00%/3/8/2568/11939/12448/00.07%/0/02.1
0% MCR=0 MCW=0
12:20:51 1803381878mS RES2: IP 500 V2 9.1.0.0 build 437 Tasks=52 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=54 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
12:20:51 1803381878mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
12:20:51 1803381878mS RES5: CLog MemObjs=428 FreePoolMem(Objs)=3744(72) TotalMem=129600
12:20:53 1803383028mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 1d ....
12:20:53 1803383028mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 1d ....
12:20:53 1803383028mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 73 ...s
12:20:53 1803383032mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 73 ...s
12:20:54 1803384257mS ISDNL1Rx: v=6 peb=6
0000 02 01 5e 36 08 01 cf 07 4c 02 00 c3 ..^6....L...
12:20:54 1803384257mS ISDNL2Rx: v=6 peb=6
0000 02 01 5e 36 08 01 cf 07 4c 02 00 c3 ..^6....L...
12:20:54 1803384257mS ISDNL3Rx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Local)
Message Type = Connect
InformationElement = ConnectedNumber
0000 4c 02 00 c3 L...
12:20:54 1803384257mS ISDNL3Evt: v=6 stacknum=6 State, new=Active, old=Proceeding id=7722
12:20:54 1803384258mS ISDNL2Tx: v=6 peb=6
0000 02 01 01 60 ...`
12:20:54 1803384258mS CMLineRx: v=6
CMConnect
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
12:20:54 1803384258mS CMCallEvt: c0a8fe0200001e2a 0.7722.0 1780 Q931 Trunk:6 CHAN=1: StateChange: END=B CMCSAccept->CMCSConnReq
12:20:54 1803384258mS CMCallEvt: c0a8fe0200001e27 0.7719.0 1780 Kate.0: StateChange: END=A CMCSDialled->CMCSOGConnReq
12:20:54 1803384258mS CMExtnEvt: v=6 State, new=Connected old=Proceeding,0,0,Kate
12:20:54 1803384259mS CMExtnTx: v=214, p1=0
CMConnect
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=02082xxxxxx
IE CMIEDeviceDetail (231) c0a8fe0200001e2a LOCALE=eng HW=15 VER=9 class=CMDeviceISDNTrunk type=0 number=6 channel=1 features=0x0 rx_gain=32 tx_gain=32 ep_callid=7722 ipaddr=192.168.254.2 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=1
Timed: 23/02/16 12:20
12:20:54 1803384259mS CMExtnRx: v=214, p1=0
CMConnectAck
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
12:20:54 1803384260mS CMCallEvt: c0a8fe0200001e27 0.7719.0 1780 Kate.0: StateChange: END=A CMCSOGConnReq->CMCSConnected
12:20:54 1803384260mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: ~CMTargetHandler f4fba018 ep f1803234
12:20:54 1803384261mS CMCallEvt: c0a8fe0200001e2a 0.7722.0 1780 Q931 Trunk:6 CHAN=1: StateChange: END=B CMCSConnReq->CMCSConnected
12:20:54 1803384262mS CMLineTx: v=6
CMConnectAck
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
BChan: slot=0 chan=1
12:20:54 1803384262mS CD: CALL: 0.7719.0 BState=Connected Cut=3 Music=0.0 Aend="Kate(214)" (20.6) Bend="Line 6" [Line 6] (3.5) CalledNum=02082xxxxxx () CallingNum=214 (Kate) Internal=0 Time=8910 AState=Connected
12:20:54 1803384262mS ISDNL1Tx: v=6 peb=6
0000 02 01 01 60 ...`
12:20:54 1803384756mS ISDNL1Rx: v=5 peb=5
0000 02 01 01 67 ...g
12:20:54 1803384756mS ISDNL2Rx: v=5 peb=5
0000 02 01 01 67 ...g
12:20:54 1803384756mS ISDNL2Tx: v=5 peb=5
0000 02 01 01 83 ....
12:20:54 1803384760mS ISDNL1Tx: v=5 peb=5
0000 02 01 01 83 ....
12:20:56 1803386349mS ISDNL1Rx: v=2 peb=2
0000 02 01 01 31 ...1
12:20:56 1803386349mS ISDNL2Rx: v=2 peb=2
0000 02 01 01 31 ...1
12:20:56 1803386349mS ISDNL2Tx: v=2 peb=2
0000 02 01 01 79 ...y
12:20:56 1803386353mS ISDNL1Tx: v=2 peb=2
0000 02 01 01 79 ...y
12:21:03 1803393040mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 1d ....
12:21:03 1803393040mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 1d ....
12:21:03 1803393041mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 73 ...s
12:21:03 1803393044mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 73 ...s
********** Warning: Logging to Screen Stopped **********
I'm having a battle with the line provider for one of our customers (Gamma). The customer wants to display the main DDI when dialling out but it keeps showing the circuit number. When I speak to Gamma they say the phone system is sending the circuit number when dialling out, but from the traces I have done, that is not the case. I was wondering if someone here could cast their eyes over the trace and see if I'm missing anything. The circuit number is 02074xxxxxx (which is not mentioned once in the trace) and the number we are trying to display is 02073xxxxxx.
Trace below.
Thanks for any help.
12:20:38 1803368667mS CMExtnEvt: v=3 State, new=Idle old=PortRecoverDelay,0,0,Aran
12:20:38 1803368668mS CMExtnTx: v=219, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 4 Call: lid=0 id=-1 in=0
Called[Aran Msgs=0 Old=2 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Aran Msgs=0]
Timed: 23/02/16 12:20
12:20:43 1803373015mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 1d ....
12:20:43 1803373015mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 1d ....
12:20:43 1803373015mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 73 ...s
12:20:43 1803373018mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 73 ...s
12:20:44 1803374744mS ISDNL1Rx: v=5 peb=5
0000 02 01 01 67 ...g
12:20:44 1803374744mS ISDNL2Rx: v=5 peb=5
0000 02 01 01 67 ...g
12:20:44 1803374744mS ISDNL2Tx: v=5 peb=5
0000 02 01 01 83 ....
12:20:44 1803374748mS ISDNL1Tx: v=5 peb=5
0000 02 01 01 83 ....
12:20:45 1803375352mS CMCallEvt: 0000000000000000 0.7719.0 -1 BaseEP: NEW CMEndpoint f1803234 TOTAL NOW=1 CALL_LIST=0
12:20:45 1803375352mS CMCallEvt: 0000000000000000 0.7719.0 -1 Kate.-1: NEW CMExtnEndpoint f1803234, Name=Kate, Extn=214, Phys Extn=214
12:20:45 1803375353mS CMCallEvt: CREATE CALL:1780 (f181d23c)
12:20:45 1803375353mS CMCallEvt: 0000000000000000 0.7720.0 -1 BaseEP: NEW CMEndpoint f181b948 TOTAL NOW=2 CALL_LIST=0
12:20:45 1803375355mS CMExtnEvt: Kate: CMExtnHandler::SetCurrent( id: 0->7719 )
12:20:45 1803375355mS CMExtnRx: v=214, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[214] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Kate
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Kate
12:20:45 1803375355mS CMCallEvt: c0a8fe0200001e27 0.7719.0 1780 Kate.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
12:20:45 1803375356mS CMExtnEvt: v=6 State, new=Dialling old=Idle,0,0,Kate
12:20:45 1803375356mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: LOOKUP CALL ROUTE: GID=0 type=100 called_party= sub= calling=214 dir=out complete=0 ses=0
12:20:45 1803375356mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
12:20:45 1803375357mS CMCallEvt: c0a8fe0200001e27 0.7719.0 1780 Kate.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
12:20:45 1803375357mS CMExtnTx: v=214, p1=0
CMSetupAck
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
BChan: slot=20 chan=6
Timed: 23/02/16 12:20
12:20:45 1803375358mS CD: CALL: 0.7719.0 BState=Idle Cut=1 Music=3.0 Aend="Kate(214)" (20.6) Bend="" [] (0.0) CalledNum= () CallingNum=214 (Kate) Internal=1 Time=6 AState=DialInitiated
12:20:45 1803375359mS CMMap: a=20.6 b=0.0 D1
12:20:45 1803375362mS RES: Tue 23/2/2016 12:20:45 FreeMem=56555732 (Heap=56154464(0) + Cache=401268) MemObjs=9239(Max 9646) CMMsg=4(4) Buff=5200 1338 1000 7427 5 Links=52399(58000) BTree=14535 CPU=04.94% CPUStats=04.23%/1/5/2568/12039/12448/00.03%/0/02.0
8% MCR=0 MCW=0
12:20:45 1803375362mS RES2: IP 500 V2 9.1.0.0 build 437 Tasks=52 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=68 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
12:20:45 1803375362mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
12:20:45 1803375363mS RES5: CLog MemObjs=428 FreePoolMem(Objs)=3744(72) TotalMem=129600
12:20:45 1803375632mS ISDNL1Rx: v=6 peb=6
0000 02 01 01 27 ...'
12:20:45 1803375632mS ISDNL2Rx: v=6 peb=6
0000 02 01 01 27 ...'
12:20:45 1803375632mS ISDNL2Tx: v=6 peb=6
0000 02 01 01 5b ...[
12:20:45 1803375636mS ISDNL1Tx: v=6 peb=6
0000 02 01 01 5b ...[
12:20:46 1803376336mS ISDNL1Rx: v=2 peb=2
0000 02 01 01 31 ...1
12:20:46 1803376336mS ISDNL2Rx: v=2 peb=2
0000 02 01 01 31 ...1
12:20:46 1803376336mS ISDNL2Tx: v=2 peb=2
0000 02 01 01 79 ...y
12:20:46 1803376340mS ISDNL1Tx: v=2 peb=2
0000 02 01 01 79 ...y
12:20:46 1803376727mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 0
12:20:46 1803376728mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
12:20:46 1803376729mS CMMap: a=20.6 b=0.0 D0
12:20:47 1803377076mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 2
12:20:47 1803377076mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
12:20:47 1803377339mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 0
12:20:47 1803377339mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
12:20:47 1803377564mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 8
12:20:47 1803377565mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[8] Type=Default (100) Reason=CMDRdirect
12:20:47 1803377565mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: CancelTimer CMTCDelayedProcessing
12:20:47 1803377565mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: LOOKUP CALL ROUTE: GID=0 type=100 called_party=0208 sub= calling=214 dir=out complete=0 ses=0
12:20:47 1803377565mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: ADD TARGET (N): number=0208 type=100 depth=1 nobar=1 setorig=1 ses=0
12:20:47 1803377566mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: DEF SC: 0208 0 sc=type=Dial code=?, num=.s02073xxxxxx
12:20:47 1803377566mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
12:20:47 1803377566mS CMARS: MakeCallTarget - Called Number: 0208
12:20:47 1803377566mS CMCallEvt: 0000000000000000 0.7721.0 -1 BaseEP: NEW CMEndpoint f181e934 TOTAL NOW=3 CALL_LIST=1
12:20:47 1803377567mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: CancelTimer CMTCDialToneTimeout
12:20:47 1803377567mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: INITIAL TARGETING SUCCEEDED
12:20:47 1803377567mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: GetNoAnswerTimer:15
12:20:47 1803377568mS CMCallEvt: 0000000000000000 0.7720.0 1780 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
12:20:47 1803377568mS CMCallEvt: 0000000000000000 0.7721.0 1780 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
12:20:47 1803377569mS CMCallEvt: 0000000000000000 0.7720.0 1780 TargetingEP: RequestEnd 0000000000000000 0.7721.0 1780 ARS for Main
12:20:47 1803377569mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: CancelTimer CMTCNoAnswerTimeout
12:20:47 1803377570mS CMCallEvt: 0000000000000000 0.7721.0 1780 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
12:20:47 1803377570mS CMARS: FORM: Main - Received Number: 0208
12:20:47 1803377570mS CMARS: FOUND A SHORT CODE - short_code: ? - Tel: .s02073xxxxxx - Called_Party: 0208 - Line Group Id: 0
12:20:47 1803377570mS CMARS: SetCurrentTarget: Short_Code: ? - Line_Group_ID: 0
12:20:47 1803377570mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
12:20:47 1803377570mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
12:20:47 1803377571mS CMCallEvt: 0000000000000000 0.7722.0 -1 BaseEP: NEW CMEndpoint f183e8dc TOTAL NOW=4 CALL_LIST=1
12:20:47 1803377571mS CMARS: ModifyCMARSTarget: Short_Code: ? - Line_Group_ID: 0 set line status to CMARS_TRYING
12:20:47 1803377572mS CMARS: FOUND LINE - Line Id: 6 - using line group id: 0 (code ?, line 0) - Called Number: 0208 - Calling Number: 02073xxxxxx
12:20:47 1803377572mS CMARS: SEND Setup TO LINE
12:20:47 1803377572mS CMCallEvt: c0a8fe0200001e2a 0.7722.0 1780 Q931 Trunk:6 CHAN=-1: StateChange: END=child CMCSIdle->CMCSOffering
12:20:47 1803377572mS CMLineTx: v=6
CMSetup
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[0208] Type=Unknown (0) Reason=CMDRdirect Calling[02073xxxxxx] Type=Unknown Plan=Unknown
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
Locale: eng
12:20:47 1803377572mS CMCallEvt: 0000000000000000 0.7720.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
12:20:47 1803377573mS CMCallEvt: 0000000000000000 0.7720.0 -1 BaseEP: DELETE CMEndpoint f181b948 TOTAL NOW=3 CALL_LIST=1
12:20:47 1803377573mS CD: CALL: 0.7719.0 BState=Idle Cut=2 Music=0.0 Aend="Kate(214)" (20.6) Bend="" [Line 6] (0.0) CalledNum=0208 () CallingNum=214 (Kate) Internal=1 Time=2221 AState=Dialling
12:20:47 1803377574mS ISDNL3Evt: v=6 stacknum=6 State, new=Initiated, old=NullState id=7722
12:20:47 1803377574mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 80 90 a3 .....
InformationElement = CallingPartyNumber
0000 6c 0d 00 80 30 32 30 37 33 32 33 33 33 35 30 l...02073xxxxxx
InformationElement = CalledPartyNumber
0000 70 05 80 30 32 30 38 p..0208
12:20:47 1803377574mS ISDNL2Tx: v=6 peb=6
0000 00 01 26 5a 08 01 4f 05 04 03 80 90 a3 6c 0d 00 ..&Z..O......l..
0010 80 30 32 30 37 33 32 33 33 33 35 30 70 05 80 30 .02073xxxxxxp..0
0020 32 30 38 208
12:20:47 1803377594mS ISDNL1Tx: v=6 peb=6
0000 00 01 26 5a 08 01 4f 05 04 03 80 90 a3 6c 0d 00 ..&Z..O......l..
0010 80 30 32 30 37 33 32 33 33 33 35 30 70 05 80 30 .02073xxxxxxp..0
0020 32 30 38 208
12:20:47 1803377602mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 28 ...(
12:20:47 1803377602mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 28 ...(
12:20:47 1803377741mS ISDNL1Rx: v=6 peb=6
0000 02 01 5a 28 08 01 cf 0d 18 01 89 ..Z(.......
12:20:47 1803377742mS ISDNL2Rx: v=6 peb=6
0000 02 01 5a 28 08 01 cf 0d 18 01 89 ..Z(.......
12:20:47 1803377742mS ISDNL3Rx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Local)
Message Type = SetupAck
InformationElement = CHI
0000 18 01 89 ...
12:20:47 1803377742mS ISDNL3Evt: v=6 stacknum=6 State, new=OverlapSend, old=Initiated id=7722
12:20:47 1803377742mS ISDNL2Tx: v=6 peb=6
0000 02 01 01 5c ... 12:20:47 1803377743mS CMLineRx: v=6
CMSetupAck
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
BChan: slot=0 chan=1
12:20:47 1803377744mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
12:20:47 1803377744mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
12:20:47 1803377744mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: c0a8fe0200001e27 0.7721.0 1780 ARS for Main - Call State: CMCSOverlapRecv
12:20:47 1803377744mS CMCallEvt: c0a8fe0200001e27 0.7721.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
12:20:47 1803377745mS CMCallEvt: c0a8fe0200001e2a 0.7722.0 1780 Q931 Trunk:6 CHAN=1: StateChange: END=B CMCSOffering->CMCSOverlapRecv
12:20:47 1803377745mS CMCallEvt: c0a8fe0200001e27 0.7721.0 -1 BaseEP: DELETE CMEndpoint f181e934 TOTAL NOW=2 CALL_LIST=1
12:20:47 1803377746mS CD: CALL: 0.7719.0 BState=Idle Cut=2 Music=0.0 Aend="Kate(214)" (20.6) Bend="Line 6" [Line 6] (3.5) CalledNum=0208 () CallingNum=214 (Kate) Internal=0 Time=2394 AState=Dialling
12:20:47 1803377746mS ISDNL1Tx: v=6 peb=6
0000 02 01 01 5c ... 12:20:47 1803377747mS CMMap: PCG::MapBChan pcp[86]b1r0 cp_b f524ab24 other_cp_b 0 type CGTypeSimple
12:20:47 1803377747mS CMMap: PCG::MapBChan pcp[73]b1r0 cp_b f567e7e0 other_cp_b f524ab24 type CGTypeSimple
12:20:47 1803377747mS CMMap: a=20.6 b=3.5 M12
12:20:47 1803377826mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 2
12:20:47 1803377826mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
12:20:47 1803377827mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:47 1803377828mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 32 p..2
12:20:47 1803377828mS ISDNL2Tx: v=6 peb=6
0000 00 01 28 5c 08 01 4f 7b 70 02 80 32 ..(\..O{p..2
12:20:47 1803377836mS ISDNL1Tx: v=6 peb=6
0000 00 01 28 5c 08 01 4f 7b 70 02 80 32 ..(\..O{p..2
12:20:47 1803377845mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 2a ...*
12:20:47 1803377845mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 2a ...*
12:20:48 1803378014mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 3
12:20:48 1803378015mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
12:20:48 1803378015mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:48 1803378016mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 33 p..3
12:20:48 1803378016mS ISDNL2Tx: v=6 peb=6
0000 00 01 2a 5c 08 01 4f 7b 70 02 80 33 ..*\..O{p..3
12:20:48 1803378024mS ISDNL1Tx: v=6 peb=6
0000 00 01 2a 5c 08 01 4f 7b 70 02 80 33 ..*\..O{p..3
12:20:48 1803378033mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 2c ...,
12:20:48 1803378033mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 2c ...,
12:20:48 1803378214mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 2
12:20:48 1803378214mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
12:20:48 1803378215mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:48 1803378216mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 32 p..2
12:20:48 1803378217mS ISDNL2Tx: v=6 peb=6
0000 00 01 2c 5c 08 01 4f 7b 70 02 80 32 ..,\..O{p..2
12:20:48 1803378224mS ISDNL1Tx: v=6 peb=6
0000 00 01 2c 5c 08 01 4f 7b 70 02 80 32 ..,\..O{p..2
12:20:48 1803378233mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 2e ....
12:20:48 1803378233mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 2e ....
12:20:48 1803378427mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 1
12:20:48 1803378427mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
12:20:48 1803378427mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:48 1803378429mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 31 p..1
12:20:48 1803378429mS ISDNL2Tx: v=6 peb=6
0000 00 01 2e 5c 08 01 4f 7b 70 02 80 31 ...\..O{p..1
12:20:48 1803378437mS ISDNL1Tx: v=6 peb=6
0000 00 01 2e 5c 08 01 4f 7b 70 02 80 31 ...\..O{p..1
12:20:48 1803378445mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 30 ...0
12:20:48 1803378446mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 30 ...0
12:20:49 1803379089mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 1
12:20:49 1803379089mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
12:20:49 1803379090mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:49 1803379091mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 31 p..1
12:20:49 1803379091mS ISDNL2Tx: v=6 peb=6
0000 00 01 30 5c 08 01 4f 7b 70 02 80 31 ..0\..O{p..1
12:20:49 1803379099mS ISDNL1Tx: v=6 peb=6
0000 00 01 30 5c 08 01 4f 7b 70 02 80 31 ..0\..O{p..1
12:20:49 1803379108mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 32 ...2
12:20:49 1803379108mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 32 ...2
12:20:49 1803379701mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 9
12:20:49 1803379702mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
12:20:49 1803379702mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:49 1803379704mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 39 p..9
12:20:49 1803379704mS ISDNL2Tx: v=6 peb=6
0000 00 01 32 5c 08 01 4f 7b 70 02 80 39 ..2\..O{p..9
12:20:49 1803379711mS ISDNL1Tx: v=6 peb=6
0000 00 01 32 5c 08 01 4f 7b 70 02 80 39 ..2\..O{p..9
12:20:49 1803379721mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 34 ...4
12:20:49 1803379721mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 34 ...4
12:20:49 1803379902mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,214: Digit Key Pressed 0
12:20:49 1803379902mS CMExtnRx: v=214, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
12:20:49 1803379903mS CMLineTx: v=6
CMInformation
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
12:20:49 1803379904mS ISDNL3Tx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 30 p..0
12:20:49 1803379904mS ISDNL2Tx: v=6 peb=6
0000 00 01 34 5c 08 01 4f 7b 70 02 80 30 ..4\..O{p..0
12:20:49 1803379912mS ISDNL1Tx: v=6 peb=6
0000 00 01 34 5c 08 01 4f 7b 70 02 80 30 ..4\..O{p..0
12:20:49 1803379920mS ISDNL1Rx: v=6 peb=6
0000 00 01 01 36 ...6
12:20:49 1803379920mS ISDNL2Rx: v=6 peb=6
0000 00 01 01 36 ...6
12:20:51 1803381589mS ISDNL1Rx: v=6 peb=6
0000 02 01 5c 36 08 01 cf 02 1e 02 8a 81 ..\6........
12:20:51 1803381589mS ISDNL2Rx: v=6 peb=6
0000 02 01 5c 36 08 01 cf 02 1e 02 8a 81 ..\6........
12:20:51 1803381589mS ISDNL3Rx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Local)
Message Type = CallProceeding
InformationElement = PI
0000 1e 02 8a 81 ....
12:20:51 1803381589mS ISDNL3Evt: v=6 stacknum=6 State, new=Proceeding, old=OverlapSend id=7722
12:20:51 1803381590mS ISDNL2Tx: v=6 peb=6
0000 02 01 01 5e ...^
12:20:51 1803381590mS CMLineRx: v=6
CMProceeding
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLNetBeyondIWPoint (10), pd=CMPDNotISDN (1)
12:20:51 1803381590mS CMCallEvt: c0a8fe0200001e2a 0.7722.0 1780 Q931 Trunk:6 CHAN=1: StateChange: END=B CMCSOverlapRecv->CMCSAccept
12:20:51 1803381591mS CMCallEvt: c0a8fe0200001e27 0.7719.0 1780 Kate.0: StateChange: END=A CMCSDialling->CMCSDialled
12:20:51 1803381591mS CMExtnEvt: v=6 State, new=Proceeding old=Dialling,0,0,Kate
12:20:51 1803381591mS CMExtnTx: v=214, p1=0
CMProceeding
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLNetBeyondIWPoint (10), pd=CMPDNotISDN (1)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=02082xxxxxx
IE CMIEDeviceDetail (231) c0a8fe0200001e2a LOCALE=eng HW=15 VER=9 class=CMDeviceISDNTrunk type=0 number=6 channel=1 features=0x0 rx_gain=32 tx_gain=32 ep_callid=7722 ipaddr=192.168.254.2 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=1
Timed: 23/02/16 12:20
12:20:51 1803381592mS CD: CALL: 0.7719.0 BState=Ringing Cut=3 Music=0.0 Aend="Kate(214)" (20.6) Bend="Line 6" [Line 6] (3.5) CalledNum=02082xxxxxx () CallingNum=214 (Kate) Internal=0 Time=6240 AState=Dialled
12:20:51 1803381593mS ISDNL1Tx: v=6 peb=6
0000 02 01 01 5e ...^
12:20:51 1803381877mS RES: Tue 23/2/2016 12:20:51 FreeMem=56552400 (Heap=56154464(0) + Cache=397936) MemObjs=9253(Max 9646) CMMsg=4(4) Buff=5200 1340 1000 7426 5 Links=52452(58000) BTree=14534 CPU=05.26% CPUStats=05.00%/3/8/2568/11939/12448/00.07%/0/02.1
0% MCR=0 MCW=0
12:20:51 1803381878mS RES2: IP 500 V2 9.1.0.0 build 437 Tasks=52 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=54 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
12:20:51 1803381878mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
12:20:51 1803381878mS RES5: CLog MemObjs=428 FreePoolMem(Objs)=3744(72) TotalMem=129600
12:20:53 1803383028mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 1d ....
12:20:53 1803383028mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 1d ....
12:20:53 1803383028mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 73 ...s
12:20:53 1803383032mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 73 ...s
12:20:54 1803384257mS ISDNL1Rx: v=6 peb=6
0000 02 01 5e 36 08 01 cf 07 4c 02 00 c3 ..^6....L...
12:20:54 1803384257mS ISDNL2Rx: v=6 peb=6
0000 02 01 5e 36 08 01 cf 07 4c 02 00 c3 ..^6....L...
12:20:54 1803384257mS ISDNL3Rx: v=6 peb=6
ISDN Layer3 Pcol=08(Q931) Reflen=1 ref=4F(Local)
Message Type = Connect
InformationElement = ConnectedNumber
0000 4c 02 00 c3 L...
12:20:54 1803384257mS ISDNL3Evt: v=6 stacknum=6 State, new=Active, old=Proceeding id=7722
12:20:54 1803384258mS ISDNL2Tx: v=6 peb=6
0000 02 01 01 60 ...`
12:20:54 1803384258mS CMLineRx: v=6
CMConnect
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
12:20:54 1803384258mS CMCallEvt: c0a8fe0200001e2a 0.7722.0 1780 Q931 Trunk:6 CHAN=1: StateChange: END=B CMCSAccept->CMCSConnReq
12:20:54 1803384258mS CMCallEvt: c0a8fe0200001e27 0.7719.0 1780 Kate.0: StateChange: END=A CMCSDialled->CMCSOGConnReq
12:20:54 1803384258mS CMExtnEvt: v=6 State, new=Connected old=Proceeding,0,0,Kate
12:20:54 1803384259mS CMExtnTx: v=214, p1=0
CMConnect
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=02082xxxxxx
IE CMIEDeviceDetail (231) c0a8fe0200001e2a LOCALE=eng HW=15 VER=9 class=CMDeviceISDNTrunk type=0 number=6 channel=1 features=0x0 rx_gain=32 tx_gain=32 ep_callid=7722 ipaddr=192.168.254.2 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=1
Timed: 23/02/16 12:20
12:20:54 1803384259mS CMExtnRx: v=214, p1=0
CMConnectAck
Line: type=DigitalExtn 3 Call: lid=0 id=7719 in=0
12:20:54 1803384260mS CMCallEvt: c0a8fe0200001e27 0.7719.0 1780 Kate.0: StateChange: END=A CMCSOGConnReq->CMCSConnected
12:20:54 1803384260mS CMTARGET: c0a8fe0200001e27 0.7719.0 1780 Kate.0: ~CMTargetHandler f4fba018 ep f1803234
12:20:54 1803384261mS CMCallEvt: c0a8fe0200001e2a 0.7722.0 1780 Q931 Trunk:6 CHAN=1: StateChange: END=B CMCSConnReq->CMCSConnected
12:20:54 1803384262mS CMLineTx: v=6
CMConnectAck
Line: type=Q931Line 6 Call: lid=0 id=7722 in=0
BChan: slot=0 chan=1
12:20:54 1803384262mS CD: CALL: 0.7719.0 BState=Connected Cut=3 Music=0.0 Aend="Kate(214)" (20.6) Bend="Line 6" [Line 6] (3.5) CalledNum=02082xxxxxx () CallingNum=214 (Kate) Internal=0 Time=8910 AState=Connected
12:20:54 1803384262mS ISDNL1Tx: v=6 peb=6
0000 02 01 01 60 ...`
12:20:54 1803384756mS ISDNL1Rx: v=5 peb=5
0000 02 01 01 67 ...g
12:20:54 1803384756mS ISDNL2Rx: v=5 peb=5
0000 02 01 01 67 ...g
12:20:54 1803384756mS ISDNL2Tx: v=5 peb=5
0000 02 01 01 83 ....
12:20:54 1803384760mS ISDNL1Tx: v=5 peb=5
0000 02 01 01 83 ....
12:20:56 1803386349mS ISDNL1Rx: v=2 peb=2
0000 02 01 01 31 ...1
12:20:56 1803386349mS ISDNL2Rx: v=2 peb=2
0000 02 01 01 31 ...1
12:20:56 1803386349mS ISDNL2Tx: v=2 peb=2
0000 02 01 01 79 ...y
12:20:56 1803386353mS ISDNL1Tx: v=2 peb=2
0000 02 01 01 79 ...y
12:21:03 1803393040mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 1d ....
12:21:03 1803393040mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 1d ....
12:21:03 1803393041mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 73 ...s
12:21:03 1803393044mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 73 ...s
********** Warning: Logging to Screen Stopped **********