Hi all, we have a PRI install that doesn't make outbound calls... the number shows as dialled, but the trunk line is never seized.
If I dial a DID of another extension (the full external number) from one handset, the other extensions rings showing External Call. On system status it shows outbound on channel 1, and incoming call on channel 2.
Very strange that I cant dial my mobile 0551777301, nor can I dial in the on the main pilot number, it doesn't ring on either my phone or incoming call ring, it just hangs up.
I have checked the config/dialplans and they are the same as other jobs we have done for this ISP (using the same equipment).
I have a trace from below, can anyone kindly advise how I can troubleshoot and what I should look for in these logs (to show any error or non-response from ISP)? This particular ISP is very hard to convince that they have a problem and will try and charge just to even look in to it.
If I dial a DID of another extension (the full external number) from one handset, the other extensions rings showing External Call. On system status it shows outbound on channel 1, and incoming call on channel 2.
Very strange that I cant dial my mobile 0551777301, nor can I dial in the on the main pilot number, it doesn't ring on either my phone or incoming call ring, it just hangs up.
I have checked the config/dialplans and they are the same as other jobs we have done for this ISP (using the same equipment).
I have a trace from below, can anyone kindly advise how I can troubleshoot and what I should look for in these logs (to show any error or non-response from ISP)? This particular ISP is very hard to convince that they have a problem and will try and charge just to even look in to it.
Code:
UNICODE-UTF8
aru
********** SysMonitor v10.1 (52) **********
********** contact made with 192.168.1.11 at 18:19:09 29/1/2013 **********
********** System (192.168.1.11) has been up and running for 5mins and 25secs(325345mS) **********
********** Warning: TEXT File Logging selected **********
********** Warning: TEXT Logging to File STOPPED on 29/1/2013 18:19:09 **********
325345mS PRN: Monitor Started IP=192.168.1.222 IP 500 V2 8.1(52) ParkerCompany
(IP Office: Supports Unicode, System Locale is default)
325345mS PRN: LAW=A PRI=1, BRI=0, ALOG=0, VCOMP=32, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=1 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=0)
329545mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
329545mS PRN: + loader: 1.32
329545mS PRN: + cpu: id 12 board 4 pld 25 type c10 options ae02
329545mS PRN: + cpu: sn 12WZ2420088X
329545mS PRN: + fpga: id 1 issue 0 build 827
329545mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
329545mS PRN: ++++++++++++++++++++ LIST OF MODULES ++++++++++++++++++++
329545mS PRN: +------------------------------------------------------------
329545mS PRN: + Slot 1: Base VCM32 Board=0x05 PLD=0x11
329545mS PRN: + Mezzanine PRIS U Board=0x03 PLD=0x0a
329545mS PRN: +------------------------------------------------------------
329546mS PRN: + Slot 2: Base NONE
329546mS PRN: + Mezzanine NONE
329546mS PRN: +------------------------------------------------------------
329546mS PRN: + Slot 3: Base NONE
329546mS PRN: + Mezzanine NONE
329546mS PRN: +------------------------------------------------------------
329546mS PRN: + Slot 4: Base NONE
329546mS PRN: + Mezzanine NONE
329546mS PRN: +------------------------------------------------------------
329546mS PRN: +++++++++++++++++ END OF LIST OF MODULES +++++++++++++++
329546mS PRN: +++++++++++++++++ LIST OF MEMORY CARDS ++++++++++++++++++
329546mS PRN: +------------------------------------------------------------
329546mS PRN: + System SD Card: MNTD
329547mS PRN: + CID Register: 0x4134325344344742300ab802df00c627
329547mS PRN: + CSD Register: 0x400e00325b5900001da77f800a40002d
329547mS PRN: + SCR Register: 0x0235800001000000
329547mS PRN: + OCR Register: 0xc0ff8000
329548mS PRN: + Status Register: 0x0000000002000000040090003206
329548mS PRN: +------------------------------------------------------------
329548mS PRN: + Optional SD Card: UMNT
329548mS PRN: +------------------------------------------------------------
329549mS PRN: ++++++++++++++ END OF LIST OF MEMORY CARDS +++++++++++++
329549mS PRN: ++++++++++++++++++++ MEMORY CAPTURE +++++++++++++++++++++
329549mS PRN: +------------------------------------------------------------
329549mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x0000000c, 0x00000000
329549mS PRN: + 0x00000000, 0x00000000, 0x0000000c, 0x00000000, 0x00000000
329549mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x0000000c, 0x00000000
329549mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
329549mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
329549mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
329549mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
329549mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
329549mS PRN: + 0x00000000, 0x510808f0, 0xd146b69b, 0xcbd20278, 0x160621cf
329550mS PRN: +------------------------------------------------------------
329550mS PRN: +++++++++++++++++ END OF MEMORY CAPTURE ++++++++++++++++
332184mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 01 ....
332184mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 01 ....
332185mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 01 ....
332185mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 01 ....
333899mS RES: Tue 29/1/2013 18:19:16 FreeMem=61300740(2) CMMsg=2 (2) Buff=5200 960 1000 7439 5 Links=7469 CPU=1/2/8885/38029/38887/0
333899mS RES2: IP 500 V2 8.1(52) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=51 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=20 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
338714mS PRN: 400: Digit Pressed 9
338715mS CMCallEvt: 0.1002.0 -1 BaseEP: NEW CMEndpoint f522cf94 TOTAL NOW=1 CALL_LIST=0
338715mS CMCallEvt: 0.1002.0 -1 Extn400.-1: NEW CMExtnEndpoint f522cf94, Name=Extn400, Extn=400, Phys Extn=400
338716mS CMCallEvt: CREATE CALL:1 (f522c230)
338716mS CMCallEvt: 0.1003.0 -1 BaseEP: NEW CMEndpoint f522ad64 TOTAL NOW=2 CALL_LIST=0
338718mS CMExtnEvt: Extn400: CMExtnHandler::SetCurrent( id: 0->1002 )
338719mS CMExtnRx: v=400, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[400] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn400
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Beth Sison
IE CMIEFastStartInfoData (6) 10 item(s)
338719mS CMCallEvt: 256.1002.0 1 Extn400.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
338719mS CMExtnEvt: v=2 State, new=Dialling old=Idle,0,0,Extn400
338720mS CMTARGET: 256.1002.0 1 Extn400.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=400 dir=out complete=0 ses=0
338720mS CMTARGET: 256.1002.0 1 Extn400.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
338720mS CMCallEvt: 256.1002.0 1 Extn400.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
338723mS CMExtnTx: v=400, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIESecurityData (253) 2 item(s)
Timed: 29/01/13 18:19
338723mS CMExtnTx: v=400, p1=8005
CMFacility
Line: type=IPLine 250 Call: lid=256 id=2 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
338724mS CMExtnTx: v=400, p1=0
CMSetupAck
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Timed: 29/01/13 18:19
338724mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
338724mS CMTARGET: 256.1002.0 1 Extn400.0: Setting Hard Timer 3000
338725mS CMTARGET: 256.1002.0 1 Extn400.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=400 dir=out complete=0 ses=0
338725mS CMTARGET: 256.1002.0 1 Extn400.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
338725mS CMTARGET: 256.1002.0 1 Extn400.0: SYS SC: 9 2 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
338725mS CMARS: FindActiveARSByGroupID GroupID=51 - Found
338725mS CMARS: MakeCallTarget - Called Number:
338726mS CMCallEvt: 0.1004.0 -1 BaseEP: NEW CMEndpoint f5220470 TOTAL NOW=3 CALL_LIST=1
338726mS CMTARGET: 256.1002.0 1 Extn400.0: CancelTimer CMTCDelayedProcessing
338726mS CMTARGET: 256.1002.0 1 Extn400.0: INITIAL TARGETING SUCCEEDED
338726mS CMTARGET: 256.1002.0 1 Extn400.0: GetNoAnswerTimer:15
338727mS CMCallEvt: 0.1003.0 1 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
338728mS CMCallEvt: 0.1004.0 1 ARS for Test: StateChange: END=T CMCSIdle->CMCSOffering
338729mS CMCallEvt: 0.1003.0 1 TargetingEP: RequestEnd 0.1004.0 1 ARS for Test
338729mS CMTARGET: 256.1002.0 1 Extn400.0: CancelTimer CMTCNoAnswerTimeout
338730mS CMCallEvt: 0.1004.0 1 ARS for Test: StateChange: END=B CMCSOffering->CMCSOverlapRecv
338730mS CMARS: FORM: Test - Received Number:
338730mS CMARS: CMARSTargetingPending
338730mS CMCallEvt: 0.1003.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
338730mS CMCallEvt: 0.1003.0 -1 BaseEP: DELETE CMEndpoint f522ad64 TOTAL NOW=2 CALL_LIST=1
338732mS CD: CALL: 256.1002.0 BState=Idle Cut=2 Music=0.0 Aend="Extn400(400)" (0.0) Bend="" [] (0.0) CalledNum=9 () CallingNum=400 (Extn400) Internal=1 Time=16 AState=Dialling
338733mS H323Evt: SetOperational 1 192.168.43.1:0 to 0
338899mS RES: Tue 29/1/2013 18:19:21 FreeMem=61242112(2) CMMsg=5 (5) Buff=5200 959 999 7439 5 Links=6992 CPU=1/2/8885/38019/38887/0
338900mS RES2: IP 500 V2 8.1(52) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=55 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=20 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
339856mS PRN: 400: Digit Pressed 0
339857mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
339857mS CMARS: FORM: Test - Received Number: 0
339857mS CMARS: FOUND A SHORT CODE - short_code: ? - Tel: . - Called_Party: 0 - Line Group Id: 0
339857mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
339858mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
339858mS CMMap: a=0.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 32
339858mS CMCallEvt: 0.1005.0 -1 BaseEP: NEW CMEndpoint f521e6f0 TOTAL NOW=3 CALL_LIST=1
339859mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 0 - Calling Number: 400
339859mS CMARS: SEND Setup TO LINE
339860mS CMCallEvt: 0.1005.0 1 Q931 Trunk:1 CHAN=-1: StateChange: END=child CMCSIdle->CMCSOffering
339860mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[0] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
Locale: aru
339861mS CD: CALL: 256.1002.0 BState=Idle Cut=2 Music=0.0 Aend="Extn400(400)" (0.0) Bend="" [Line 1] (0.0) CalledNum=90 () CallingNum=400 (Extn400) Internal=1 Time=1145 AState=Dialling
339862mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=1005
339862mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 80 90 a3 .....
InformationElement = CalledPartyNumber
0000 70 02 80 30 p..0
339862mS ISDNL2Tx: v=1 peb=1
0000 00 01 00 00 08 02 00 01 05 04 03 80 90 a3 70 02 ..............p.
0010 80 30 .0
339862mS ISDNL1Tx: v=1 peb=1
0000 00 01 00 00 08 02 00 01 05 04 03 80 90 a3 70 02 ..............p.
0010 80 30 .0
339868mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 02 ....
339868mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 02 ....
339870mS ISDNL1Rx: v=1 peb=1
0000 02 01 00 02 08 02 80 01 0d 18 03 a9 83 81 ..............
339870mS ISDNL2Rx: v=1 peb=1
0000 02 01 00 02 08 02 80 01 0d 18 03 a9 83 81 ..............
339871mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = SetupAck
InformationElement = CHI
0000 18 03 a9 83 81 .....
339871mS ISDNL3Evt: v=1 stacknum=1 State, new=OverlapSend, old=Initiated id=1005
339871mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 02 ....
339872mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 02 ....
339872mS CMLineRx: v=1
CMSetupAck
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
BChan: slot=0 chan=1
339875mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
339875mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
339876mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.1004.0 1 ARS for Test - Call State: CMCSOverlapRecv
339876mS CMCallEvt: 0.1004.0 -1 ARS for Test: StateChange: END=X CMCSOverlapRecv->CMCSDelete
339877mS CMCallEvt: 0.1005.0 1 Q931 Trunk:1 CHAN=1: StateChange: END=B CMCSOffering->CMCSOverlapRecv
339878mS CMCallEvt: 0.1004.0 -1 BaseEP: DELETE CMEndpoint f5220470 TOTAL NOW=2 CALL_LIST=1
339879mS CD: CALL: 256.1002.0 BState=Idle Cut=2 Music=0.0 Aend="Extn400(400)" (0.0) Bend="Line 1" [Line 1] (1.2) CalledNum=0 () CallingNum=400 (Extn400) Internal=0 Time=1163 AState=Dialling
339880mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
339880mS CMMap: PCGS CPReserveCodec for the other end (pcp[119]b0r1) true
339880mS CMMap: PCG::MapBChan pcp[89]b1r0 cp_b f59578c8 other_cp_b f59a28a8 type CGTypeSimple
339880mS CMMap: a=0.1 b=1.2 M12
339880mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[119]b0r1 Configure 0.1
339880mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[119]b0r1 ConnectIndication 0.1
339880mS H323Evt: SetOperational 1 192.168.43.1:49152 to 1
340259mS PRN: 400: Digit Pressed 5
340259mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect Keypad[5]
340260mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
340261mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
340261mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
340262mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
340262mS CMMap: a=1.2 b=0.1 M12
340262mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 35 p..5
340262mS ISDNL2Tx: v=1 peb=1
0000 00 01 02 02 08 02 00 01 7b 70 02 80 35 ........{p..5
340262mS ISDNL1Tx: v=1 peb=1
0000 00 01 02 02 08 02 00 01 7b 70 02 80 35 ........{p..5
340268mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 04 ....
340268mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 04 ....
340926mS PRN: 400: Digit Pressed 0
340927mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
340928mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
340929mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
340929mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
340929mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
340929mS CMMap: a=1.2 b=0.1 M12
340930mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 30 p..0
340930mS ISDNL2Tx: v=1 peb=1
0000 00 01 04 02 08 02 00 01 7b 70 02 80 30 ........{p..0
340930mS ISDNL1Tx: v=1 peb=1
0000 00 01 04 02 08 02 00 01 7b 70 02 80 30 ........{p..0
340935mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 06 ....
340935mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 06 ....
340995mS H323Evt: RTP(50t): 192.168.43.1/49152 192.168.43.2/51786 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=2262 SENT=50 (avg size=160) RECV=56 (avg size=160)
341666mS PRN: 400: Digit Pressed 1
341667mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
341667mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
341669mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
341669mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
341669mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
341669mS CMMap: a=1.2 b=0.1 M12
341670mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 31 p..1
341670mS ISDNL2Tx: v=1 peb=1
0000 00 01 06 02 08 02 00 01 7b 70 02 80 31 ........{p..1
341670mS ISDNL1Tx: v=1 peb=1
0000 00 01 06 02 08 02 00 01 7b 70 02 80 31 ........{p..1
341675mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 08 ....
341675mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 08 ....
342028mS PRN: 400: Digit Pressed 7
342028mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
342029mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
342030mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
342030mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
342031mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
342031mS CMMap: a=1.2 b=0.1 M12
342031mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 37 p..7
342031mS ISDNL2Tx: v=1 peb=1
0000 00 01 08 02 08 02 00 01 7b 70 02 80 37 ........{p..7
342031mS ISDNL1Tx: v=1 peb=1
0000 00 01 08 02 08 02 00 01 7b 70 02 80 37 ........{p..7
342037mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 0a ....
342037mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 0a ....
342358mS PRN: 400: Digit Pressed 0
342359mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
342359mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
342361mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
342361mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
342361mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
342361mS CMMap: a=1.2 b=0.1 M12
342362mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 30 p..0
342362mS ISDNL2Tx: v=1 peb=1
0000 00 01 0a 02 08 02 00 01 7b 70 02 80 30 ........{p..0
342362mS ISDNL1Tx: v=1 peb=1
0000 00 01 0a 02 08 02 00 01 7b 70 02 80 30 ........{p..0
342367mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 0c ....
342367mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 0c ....
342687mS PRN: 400: Digit Pressed 5
342687mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect Keypad[5]
342688mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
342689mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
342689mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
342689mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
342690mS CMMap: a=1.2 b=0.1 M12
342690mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 35 p..5
342690mS ISDNL2Tx: v=1 peb=1
0000 00 01 0c 02 08 02 00 01 7b 70 02 80 35 ........{p..5
342690mS ISDNL1Tx: v=1 peb=1
0000 00 01 0c 02 08 02 00 01 7b 70 02 80 35 ........{p..5
342695mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 0e ....
342696mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 0e ....
342961mS PRN: 400: Digit Pressed 2
342962mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[2] Type=Default (100) Reason=CMDRdirect Keypad[2]
342963mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
342964mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
342964mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
342964mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
342964mS CMMap: a=1.2 b=0.1 M12
342965mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 32 p..2
342965mS ISDNL2Tx: v=1 peb=1
0000 00 01 0e 02 08 02 00 01 7b 70 02 80 32 ........{p..2
342970mS ISDNL1Tx: v=1 peb=1
0000 00 01 0e 02 08 02 00 01 7b 70 02 80 32 ........{p..2
342970mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 10 ....
342971mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 10 ....
343318mS PRN: 400: Digit Pressed 0
343319mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
343320mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
343321mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
343321mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
343321mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
343321mS CMMap: a=1.2 b=0.1 M12
343322mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 30 p..0
343322mS ISDNL2Tx: v=1 peb=1
0000 00 01 10 02 08 02 00 01 7b 70 02 80 30 ........{p..0
343322mS ISDNL1Tx: v=1 peb=1
0000 00 01 10 02 08 02 00 01 7b 70 02 80 30 ........{p..0
343327mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 12 ....
343327mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 12 ....
343707mS PRN: 400: Digit Pressed 9
343708mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
343708mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
343710mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
343710mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
343710mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
343710mS CMMap: a=1.2 b=0.1 M12
343711mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 39 p..9
343711mS ISDNL2Tx: v=1 peb=1
0000 00 01 12 02 08 02 00 01 7b 70 02 80 39 ........{p..9
343711mS ISDNL1Tx: v=1 peb=1
0000 00 01 12 02 08 02 00 01 7b 70 02 80 39 ........{p..9
343716mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 14 ....
343716mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 14 ....
343901mS RES: Tue 29/1/2013 18:19:25 FreeMem=61213776(2) CMMsg=5 (5) Buff=5200 959 999 7439 5 Links=6710 CPU=5/21/8885/24671/38887/0
343902mS RES2: IP 500 V2 8.1(52) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=53 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=20 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
346721mS ISDNL1Rx: v=1 peb=1
0000 02 01 02 14 08 02 80 01 02 .........
346721mS ISDNL2Rx: v=1 peb=1
0000 02 01 02 14 08 02 80 01 02 .........
346721mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = CallProceeding
346721mS ISDNL3Evt: v=1 stacknum=1 State, new=Proceeding, old=OverlapSend id=1005
346722mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 04 ....
346722mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 04 ....
346722mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
346723mS CMCallEvt: 0.1005.0 1 Q931 Trunk:1 CHAN=1: StateChange: END=B CMCSOverlapRecv->CMCSAccept
346723mS CMCallEvt: 256.1002.0 1 Extn400.0: StateChange: END=A CMCSDialling->CMCSDialled
346723mS CMExtnEvt: v=2 State, new=Proceeding old=Dialling,0,0,Extn400
346724mS CMExtnTx: v=400, p1=0
CMProceeding
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=90551777301
IE CMIEDeviceDetail (231) LOCALE=aru HW=15 VER=8 class=CMDeviceISDNTrunk type=0 number=1 channel=1 rx_gain=32 tx_gain=32 ep_callid=1005 ipaddr=192.168.43.1 apps=0
Timed: 29/01/13 18:19
346726mS CD: CALL: 256.1002.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn400(400)" (0.0) Bend="Line 1" [Line 1] (1.2) CalledNum=0551777301 () CallingNum=400 (Extn400) Internal=0 Time=8010 AState=Dialled
346727mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
346727mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[119]b0r1) true
346727mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
346727mS CMMap: a=1.2 b=0.1 M12
349403mS RES: Tue 29/1/2013 18:19:31 FreeMem=61235808(2) CMMsg=5 (5) Buff=5200 960 999 7439 5 Links=6752 CPU=2/4/8885/37350/38887/0
349403mS RES2: IP 500 V2 8.1(52) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=53 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=20 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
354054mS H323Evt: Recv: RegistrationRequest 192.168.43.3; Endpoints registered: 2; Endpoints in registration: 0
355067mS H323Evt: Recv: RegistrationRequest 192.168.43.2; Endpoints registered: 2; Endpoints in registration: 0
356054mS PRN: IP408_FEC(0)::Tick: received 1000 tagged frames (this VLAN ID is 49195)
356723mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 15 ....
356723mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 15 ....
356723mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 05 ....
356723mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 05 ....
361277mS R2DSP: PRIU DSP 1: is alive
366728mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 15 ....
366728mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 15 ....
366728mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 05 ....
366728mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 05 ....
375855mS PRN: IP408_FEC(0)::Tick: received 2000 tagged frames (this VLAN ID is 49195)
376733mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 15 ....
376733mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 15 ....
376733mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 05 ....
376733mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 05 ....
********** SysMonitor v10.1 (52) [connected to 192.168.1.11 (ParkerCompany)] **********
386047mS PRN: Monitor Status IP 500 V2 8.1(52)
386047mS PRN: LAW=A PRI=1, BRI=0, ALOG=0, VCOMP=32, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=1 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=1(TOT=1)
386738mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 15 ....
386738mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 15 ....
386738mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 05 ....
386738mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 05 ....
395657mS PRN: IP408_FEC(0)::Tick: received 3000 tagged frames (this VLAN ID is 49195)
396743mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 15 ....
396743mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 15 ....
396743mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 05 ....
396743mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 05 ....
397765mS PRN: Created WAV file MOH Source 1
397905mS RES: Tue 29/1/2013 18:20:20 FreeMem=60514564(2) CMMsg=5 (5) Buff=5200 959 1000 7439 5 Links=6750 CPU=1/2/8885/37434/38887/0
397906mS RES2: IP 500 V2 8.1(52) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=48 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=20 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
397968mS PRN: Loading HoldMusic.wav from ipaddr=255.255.255.255
399872mS ISDNL1Rx: v=1 peb=1
0000 02 01 04 14 08 02 80 01 5a ........Z
399873mS ISDNL2Rx: v=1 peb=1
0000 02 01 04 14 08 02 80 01 5a ........Z
399873mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = ReleaseComplete
399873mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Proceeding id=1005
399873mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 06 ....
399874mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
BChan: slot=0 chan=1
399874mS CMCallEvt: 0.1005.0 1 Q931 Trunk:1 CHAN=1: StateChange: END=B CMCSAccept->CMCSCompleted
399875mS CMExtnTx: v=400, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 29/01/13 18:20
399875mS CMExtnTx: v=400, p1=8005
CMFacility
Line: type=IPLine 250 Call: lid=256 id=2 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
399877mS CMLOGGING: CALL:2013/01/2918:19,00:00:00,000,400,O,0551777301,90551777301,Extn400,,,0,,""n/a,0
399878mS CD: CALL: 256.1002.0 BState=Disconnecting Cut=1 Music=0.0 Aend="Extn400(400)" (0.0) Bend="Line 1" [Line 1] (1.2) CalledNum=0551777301 () CallingNum=400 (Extn400) Internal=0 Time=61162 AState=Dialled
399878mS CD: CALL: 256.1002.0 Deleted
399879mS CMExtnEvt: Extn400: CALL LOST (CMCauseNormal)
399879mS CMExtnEvt: Extn400: Extn(400) Calling Party Number(400) Type(CMNTypeInternal)
399880mS CMCallEvt: 256.1002.0 -1 Extn400.0: StateChange: END=X CMCSDialled->CMCSCompleted
399880mS CMExtnEvt: v=2 State, new=PortRecoverDelay old=Proceeding,0,0,Extn400
399880mS CMExtnTx: v=400, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=256 id=1002 in=0
Called[90551777301] Type=Default (100) Reason=CMDRdirect Calling[400] Type=Internal Plan=Default
Cause=16, Normal call clearing
Timed: 29/01/13 18:20
399881mS CMExtnTx: v=400, p1=8005
CMFacility
Line: type=IPLine 250 Call: lid=256 id=2 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
399881mS CMExtnEvt: Extn400: CMExtnHandler::SetCurrent( id: 1002->0 )
399882mS CMCallEvt: 256.1002.0 -1 Extn400.-1: StateChange: END=X CMCSCompleted->CMCSDelete
399883mS CMTARGET: 256.1002.0 -1 BaseEP: ~CMTargetHandler f5224e74 ep f522cf94
399883mS CMCallEvt: 256.1002.0 -1 BaseEP: DELETE CMEndpoint f522cf94 TOTAL NOW=1 CALL_LIST=0
399883mS CMCallEvt: 0.1005.0 -1 Q931 Trunk:1 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
399884mS CMCallEvt: END CALL:1 (f522c230)
399885mS CMCallEvt: 0.1005.0 -1 BaseEP: DELETE CMEndpoint f521e6f0 TOTAL NOW=0 CALL_LIST=0
399885mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 06 ....
399887mS CMMap: PCG::UnmapBChan pcp[89]b1r0 cp_b f59578c8 other_cp_b f59a28a8
399887mS CMMap: a=0.1 b=1.2 M02
399887mS H323Evt: SetOperational 1 192.168.43.1:49152 to 0
399888mS CMMap: PCG::UnmapBChan pcp[119]b0r1 cp_b 0 other_cp_b 0
399888mS H323Evt: RTP(END): 192.168.43.1/49152 192.168.43.2/51786 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=61155 SENT=2993 RECV=3000 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
399889mS CMMap: a=0.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 1, total 32
401882mS CMExtnEvt: Extn400: Recover Timer reason=CMTRWrapUp
401882mS CMExtnEvt: v=2 State, new=Idle old=PortRecoverDelay,0,0,Extn400
401882mS CMExtnTx: v=400, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[Extn400 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn400 Msgs=0]
Timed: 29/01/13 18:20
409057mS H323Evt: Recv: RegistrationRequest 192.168.43.3; Endpoints registered: 2; Endpoints in registration: 0
409876mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 15 ....
409876mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 15 ....
409876mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 07 ....
409876mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 07 ....
410067mS H323Evt: Recv: RegistrationRequest 192.168.43.2; Endpoints registered: 2; Endpoints in registration: 0
412118mS PRN: 400: Digit Pressed 9
412119mS CMCallEvt: 0.1006.0 -1 BaseEP: NEW CMEndpoint f522cf94 TOTAL NOW=1 CALL_LIST=0
412120mS CMCallEvt: 0.1006.0 -1 Extn400.-1: NEW CMExtnEndpoint f522cf94, Name=Extn400, Extn=400, Phys Extn=400
412120mS CMCallEvt: CREATE CALL:2 (f522c230)
412120mS CMCallEvt: 0.1007.0 -1 BaseEP: NEW CMEndpoint f522ad64 TOTAL NOW=2 CALL_LIST=0
412122mS CMExtnEvt: Extn400: CMExtnHandler::SetCurrent( id: 0->1006 )
412123mS CMExtnRx: v=400, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[400] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn400
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Beth Sison
IE CMIEFastStartInfoData (6) 10 item(s)
412123mS CMCallEvt: 256.1006.0 2 Extn400.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
412123mS CMExtnEvt: v=2 State, new=Dialling old=Idle,0,0,Extn400
412124mS CMTARGET: 256.1006.0 2 Extn400.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=400 dir=out complete=0 ses=0
412124mS CMTARGET: 256.1006.0 2 Extn400.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
412124mS CMCallEvt: 256.1006.0 2 Extn400.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
412127mS CMExtnTx: v=400, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIESecurityData (253) 2 item(s)
Timed: 29/01/13 18:20
412127mS CMExtnTx: v=400, p1=8005
CMFacility
Line: type=IPLine 250 Call: lid=256 id=2 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
412128mS CMExtnTx: v=400, p1=0
CMSetupAck
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Timed: 29/01/13 18:20
412128mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
412129mS CMTARGET: 256.1006.0 2 Extn400.0: Setting Hard Timer 3000
412129mS CMTARGET: 256.1006.0 2 Extn400.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=400 dir=out complete=0 ses=0
412129mS CMTARGET: 256.1006.0 2 Extn400.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
412129mS CMTARGET: 256.1006.0 2 Extn400.0: SYS SC: 9 2 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
412130mS CMARS: FindActiveARSByGroupID GroupID=51 - Found
412130mS CMARS: MakeCallTarget - Called Number:
412130mS CMCallEvt: 0.1008.0 -1 BaseEP: NEW CMEndpoint f5220470 TOTAL NOW=3 CALL_LIST=1
412131mS CMTARGET: 256.1006.0 2 Extn400.0: CancelTimer CMTCDelayedProcessing
412131mS CMTARGET: 256.1006.0 2 Extn400.0: INITIAL TARGETING SUCCEEDED
412131mS CMTARGET: 256.1006.0 2 Extn400.0: GetNoAnswerTimer:15
412132mS CMCallEvt: 0.1007.0 2 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
412133mS CMCallEvt: 0.1008.0 2 ARS for Test: StateChange: END=T CMCSIdle->CMCSOffering
412133mS CMCallEvt: 0.1007.0 2 TargetingEP: RequestEnd 0.1008.0 2 ARS for Test
412133mS CMTARGET: 256.1006.0 2 Extn400.0: CancelTimer CMTCNoAnswerTimeout
412134mS CMCallEvt: 0.1008.0 2 ARS for Test: StateChange: END=B CMCSOffering->CMCSOverlapRecv
412134mS CMARS: FORM: Test - Received Number:
412134mS CMARS: CMARSTargetingPending
412135mS CMCallEvt: 0.1007.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
412135mS CMCallEvt: 0.1007.0 -1 BaseEP: DELETE CMEndpoint f522ad64 TOTAL NOW=2 CALL_LIST=1
412136mS CD: CALL: 256.1006.0 BState=Idle Cut=2 Music=0.0 Aend="Extn400(400)" (0.0) Bend="" [] (0.0) CalledNum=9 () CallingNum=400 (Extn400) Internal=1 Time=16 AState=Dialling
412137mS H323Evt: SetOperational 2 192.168.43.1:0 to 0
412407mS RES: Tue 29/1/2013 18:20:34 FreeMem=60511324(2) CMMsg=5 (5) Buff=5200 960 999 7439 5 Links=6757 CPU=19/25/8885/37760/38887/0
412407mS RES2: IP 500 V2 8.1(52) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=54 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=20 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
412899mS PRN: 400: Digit Pressed 0
412900mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
412900mS CMARS: FORM: Test - Received Number: 0
412900mS CMARS: FOUND A SHORT CODE - short_code: ? - Tel: . - Called_Party: 0 - Line Group Id: 0
412900mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
412900mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
412901mS CMMap: a=0.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 32
412901mS CMCallEvt: 0.1009.0 -1 BaseEP: NEW CMEndpoint f521e6f0 TOTAL NOW=3 CALL_LIST=1
412902mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 0 - Calling Number: 400
412902mS CMARS: SEND Setup TO LINE
412902mS CMCallEvt: 0.1009.0 2 Q931 Trunk:1 CHAN=-1: StateChange: END=child CMCSIdle->CMCSOffering
412903mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
Called[0] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
Locale: aru
412903mS CD: CALL: 256.1006.0 BState=Idle Cut=2 Music=0.0 Aend="Extn400(400)" (0.0) Bend="" [Line 1] (0.0) CalledNum=90 () CallingNum=400 (Extn400) Internal=1 Time=783 AState=Dialling
412904mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=1009
412905mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 80 90 a3 .....
InformationElement = CalledPartyNumber
0000 70 02 80 30 p..0
412905mS ISDNL2Tx: v=1 peb=1
0000 00 01 14 06 08 02 00 02 05 04 03 80 90 a3 70 02 ..............p.
0010 80 30 .0
412905mS ISDNL1Tx: v=1 peb=1
0000 00 01 14 06 08 02 00 02 05 04 03 80 90 a3 70 02 ..............p.
0010 80 30 .0
412911mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 16 ....
412911mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 16 ....
412913mS ISDNL1Rx: v=1 peb=1
0000 02 01 06 16 08 02 80 02 0d 18 03 a9 83 81 ..............
412913mS ISDNL2Rx: v=1 peb=1
0000 02 01 06 16 08 02 80 02 0d 18 03 a9 83 81 ..............
412913mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Local)
Message Type = SetupAck
InformationElement = CHI
0000 18 03 a9 83 81 .....
412913mS ISDNL3Evt: v=1 stacknum=1 State, new=OverlapSend, old=Initiated id=1009
412914mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 08 ....
412914mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 08 ....
412915mS CMLineRx: v=1
CMSetupAck
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
BChan: slot=0 chan=1
412918mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
412918mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
412918mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.1008.0 2 ARS for Test - Call State: CMCSOverlapRecv
412918mS CMCallEvt: 0.1008.0 -1 ARS for Test: StateChange: END=X CMCSOverlapRecv->CMCSDelete
412920mS CMCallEvt: 0.1009.0 2 Q931 Trunk:1 CHAN=1: StateChange: END=B CMCSOffering->CMCSOverlapRecv
412921mS CMCallEvt: 0.1008.0 -1 BaseEP: DELETE CMEndpoint f5220470 TOTAL NOW=2 CALL_LIST=1
412921mS CD: CALL: 256.1006.0 BState=Idle Cut=2 Music=0.0 Aend="Extn400(400)" (0.0) Bend="Line 1" [Line 1] (1.2) CalledNum=0 () CallingNum=400 (Extn400) Internal=0 Time=801 AState=Dialling
412922mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
412922mS CMMap: PCGS CPReserveCodec for the other end (pcp[120]b0r1) true
412922mS CMMap: PCG::MapBChan pcp[89]b1r0 cp_b f59578c8 other_cp_b f59a28a8 type CGTypeSimple
412922mS CMMap: a=0.1 b=1.2 M12
412923mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[120]b0r1 Configure 0.1
412923mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[120]b0r1 ConnectIndication 0.1
412923mS H323Evt: SetOperational 2 192.168.43.1:49152 to 1
413213mS PRN: 400: Digit Pressed 5
413214mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[5] Type=Default (100) Reason=CMDRdirect Keypad[5]
413214mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
413216mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
413216mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
413216mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
413216mS CMMap: a=1.2 b=0.1 M12
413217mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 35 p..5
413217mS ISDNL2Tx: v=1 peb=1
0000 00 01 16 08 08 02 00 02 7b 70 02 80 35 ........{p..5
413217mS ISDNL1Tx: v=1 peb=1
0000 00 01 16 08 08 02 00 02 7b 70 02 80 35 ........{p..5
413222mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 18 ....
413222mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 18 ....
413503mS PRN: 400: Digit Pressed 0
413503mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
413504mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
413505mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
413505mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
413505mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
413506mS CMMap: a=1.2 b=0.1 M12
413506mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 30 p..0
413506mS ISDNL2Tx: v=1 peb=1
0000 00 01 18 08 08 02 00 02 7b 70 02 80 30 ........{p..0
413506mS ISDNL1Tx: v=1 peb=1
0000 00 01 18 08 08 02 00 02 7b 70 02 80 30 ........{p..0
413511mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 1a ....
413512mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 1a ....
413802mS PRN: 400: Digit Pressed 1
413803mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
413804mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
413805mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
413805mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
413805mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
413805mS CMMap: a=1.2 b=0.1 M12
413806mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 31 p..1
413806mS ISDNL2Tx: v=1 peb=1
0000 00 01 1a 08 08 02 00 02 7b 70 02 80 31 ........{p..1
413806mS ISDNL1Tx: v=1 peb=1
0000 00 01 1a 08 08 02 00 02 7b 70 02 80 31 ........{p..1
413811mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 1c ....
413811mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 1c ....
414022mS H323Evt: RTP(50t): 192.168.43.1/49152 192.168.43.2/51786 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=1884 SENT=50 (avg size=160) RECV=55 (avg size=160)
414093mS PRN: 400: Digit Pressed 7
414094mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
414094mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
414096mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
414096mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
414096mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
414096mS CMMap: a=1.2 b=0.1 M12
414096mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 37 p..7
414097mS ISDNL2Tx: v=1 peb=1
0000 00 01 1c 08 08 02 00 02 7b 70 02 80 37 ........{p..7
414097mS ISDNL1Tx: v=1 peb=1
0000 00 01 1c 08 08 02 00 02 7b 70 02 80 37 ........{p..7
414102mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 1e ....
414102mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 1e ....
414383mS PRN: 400: Digit Pressed 0
414383mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
414384mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
414385mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
414385mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
414386mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
414386mS CMMap: a=1.2 b=0.1 M12
414386mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 30 p..0
414386mS ISDNL2Tx: v=1 peb=1
0000 00 01 1e 08 08 02 00 02 7b 70 02 80 30 ........{p..0
414386mS ISDNL1Tx: v=1 peb=1
0000 00 01 1e 08 08 02 00 02 7b 70 02 80 30 ........{p..0
414392mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 20 ...
414392mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 20 ...
415011mS PRN: 400: Digit Pressed 5
415012mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[5] Type=Default (100) Reason=CMDRdirect Keypad[5]
415013mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
415014mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
415014mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
415014mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
415014mS CMMap: a=1.2 b=0.1 M12
415015mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 35 p..5
415015mS ISDNL2Tx: v=1 peb=1
0000 00 01 20 08 08 02 00 02 7b 70 02 80 35 .. .....{p..5
415015mS ISDNL1Tx: v=1 peb=1
0000 00 01 20 08 08 02 00 02 7b 70 02 80 35 .. .....{p..5
415020mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 22 ..."
415020mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 22 ..."
415273mS PRN: 400: Digit Pressed 2
415273mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[2] Type=Default (100) Reason=CMDRdirect Keypad[2]
415274mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
415275mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
415275mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
415276mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
415276mS CMMap: a=1.2 b=0.1 M12
415276mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 32 p..2
415276mS ISDNL2Tx: v=1 peb=1
0000 00 01 22 08 08 02 00 02 7b 70 02 80 32 ..".....{p..2
415276mS ISDNL1Tx: v=1 peb=1
0000 00 01 22 08 08 02 00 02 7b 70 02 80 32 ..".....{p..2
415282mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 24 ...$
415282mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 24 ...$
415601mS PRN: 400: Digit Pressed 0
415602mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
415602mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
415604mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
415604mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
415604mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
415604mS CMMap: a=1.2 b=0.1 M12
415605mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 30 p..0
415605mS ISDNL2Tx: v=1 peb=1
0000 00 01 24 08 08 02 00 02 7b 70 02 80 30 ..$.....{p..0
415605mS ISDNL1Tx: v=1 peb=1
0000 00 01 24 08 08 02 00 02 7b 70 02 80 30 ..$.....{p..0
415610mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 26 ...&
415610mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 26 ...&
415883mS PRN: 400: Digit Pressed 9
415883mS CMExtnRx: v=400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
415884mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=1
415885mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
415886mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
415886mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
415886mS CMMap: a=1.2 b=0.1 M12
415886mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Remote)
Message Type = Information
InformationElement = CalledPartyNumber
0000 70 02 80 39 p..9
415886mS ISDNL2Tx: v=1 peb=1
0000 00 01 26 08 08 02 00 02 7b 70 02 80 39 ..&.....{p..9
415887mS ISDNL1Tx: v=1 peb=1
0000 00 01 26 08 08 02 00 02 7b 70 02 80 39 ..&.....{p..9
415892mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 28 ...(
415892mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 28 ...(
418895mS ISDNL1Rx: v=1 peb=1
0000 02 01 08 28 08 02 80 02 02 ...(.....
418895mS ISDNL2Rx: v=1 peb=1
0000 02 01 08 28 08 02 80 02 02 ...(.....
418895mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0002(Local)
Message Type = CallProceeding
418896mS ISDNL3Evt: v=1 stacknum=1 State, new=Proceeding, old=OverlapSend id=1009
418896mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 0a ....
418896mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 0a ....
418897mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
418897mS CMCallEvt: 0.1009.0 2 Q931 Trunk:1 CHAN=1: StateChange: END=B CMCSOverlapRecv->CMCSAccept
418897mS CMCallEvt: 256.1006.0 2 Extn400.0: StateChange: END=A CMCSDialling->CMCSDialled
418898mS CMExtnEvt: v=2 State, new=Proceeding old=Dialling,0,0,Extn400
418899mS CMExtnTx: v=400, p1=0
CMProceeding
Line: type=IPLine 250 Call: lid=256 id=1006 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=90551777301
IE CMIEDeviceDetail (231) LOCALE=aru HW=15 VER=8 class=CMDeviceISDNTrunk type=0 number=1 channel=1 rx_gain=32 tx_gain=32 ep_callid=1009 ipaddr=192.168.43.1 apps=0
Timed: 29/01/13 18:20
418901mS CD: CALL: 256.1006.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn400(400)" (0.0) Bend="Line 1" [Line 1] (1.2) CalledNum=0551777301 () CallingNum=400 (Extn400) Internal=0 Time=6780 AState=Dialled
418901mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
418901mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[120]b0r1) true
418901mS CMMap: PCG::MapBChan pcp[120]b0r1 cp_b f59a28a8 other_cp_b f59578c8 type CGTypeSimple
418902mS CMMap: a=1.2 b=0.1 M12
418907mS RES: Tue 29/1/2013 18:20:41 FreeMem=60513728(2) CMMsg=5 (5) Buff=5200 959 999 7439 5 Links=6750 CPU=1/2/8885/38190/38887/0
418908mS RES2: IP 500 V2 8.1(52) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=54 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=20 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
421277mS R2DSP: PRIU DSP 1: is alive
426789mS PRN: IP408_FEC(0)::Tick: received 4000 tagged frames (this VLAN ID is 49195)
428898mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 29 ...)
428898mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 29 ...)
428898mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 0b ....
428898mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 0b ....
438903mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 29 ...)
438903mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 29 ...)
438903mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 0b ....
438903mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 0b ....
439742mS H323Evt: IRCheckTimer: GRQ record deleted (user name=; extn-num=403)
439742mS H323Evt: IRCheckTimer: GRQ record deleted (user name=; extn-num=400)
439911mS RES: Tue 29/1/2013 18:21:01 FreeMem=60513392(2) CMMsg=5 (5) Buff=5200 960 1000 7439 5 Links=6751 CPU=1/2/8885/37601/38887/0
439911mS RES2: IP 500 V2 8.1(52) Tasks=44 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=53 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=20 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
443145mS PRN: WAV Hold Music HoldMusic.wav failed to load
443146mS
