Cannont call out on Mega Path PRI 1

[iedatamaster]

I got a new mega Path PRI line with 8 channels. I have the card configured and able to RECIVE calls. however i am unable to dial out.

When ever i do i get "Call Rejected". I called megapath and they ran debug on the adtran to see what i was sending. and It aprears that the Avaya is not sending the number i wanta dial before trying to pick up the channel....How do i configre this so we can move off the 4 POTS lines.

I support NBX systems. This Avaya is by far the hardest to configure any help?

_andrew

 

[DigitelD]

Only hard because you are not used to it.
Set up your ARS route. Make sure to match you line group id. Set your dial out short code, 9N to dial out using the ARS route you configured.
For example, the short code will be
9N
Dial
N
50 (ARS route)



SHK Certified (School of Hard Knocks)
NCSS, ATSP/IP

 

[iedatamaster]

I have the exact route... This unit was previously set up for 4 analog lines... does anything else need to be set on the pri config or system to be able to dial out correctly.

 

[amriddle01]

It aprears that the Avaya is not sending the number i wanta dial before trying to pick up the channel

What do you mean by that? Do you mean it's sending digits as dialled rather than en-bloc. If so it's easily fixed

 

[DigitelD]

Inspect your ARS table very closely. The number you are dialing may match an entry that does not allow the rest of your dialed numbers to be passed.

SHK Certified (School of Hard Knocks)
NCSS, ATSP/IP

 

[jplissonneau]

If you are still stuck after reviewing your short codes / ARS tables (the "matching" and "en bloc" issues are likely here) use the monitor to trace what's happening. The ISDN tab allows you to get hex dumps of what is sent through the D channel.

If you see your correct called number there but it still doesn't work ONLY THEN you may want to try a different switch type in the PRI setup and see what happens. Sometimes the carrier will tell you to use "NI2" and then setting the switch type to anything but "NI2" may work.

--
A(PS|CI)S-SMEC
IT consultant
Canada / Quebec City area

 

[iedatamaster]

here is what the avaya is sending to the adtran

16:19:26 ISDN.L2_FMT PRI 1 =====================================================
16:19:26 ISDN.L2_FMT PRI 1 Recd = Sapi:00 C/R:R Tei:00
16:19:26 ISDN.L2_FMT PRI 1 Ctl:INFO Ns:42 Nr:41
16:19:26 ISDN.L2_FMT PRI 1 Prot:08 CRL:2 CRV:001E
16:19:26 ISDN.L2_FMT PRI 1 M - 05 SETUP
16:19:26 ISDN.L2_FMT PRI 1 IE - 04 BEARER CAPABILITY Len=3
16:19:26 ISDN.L2_FMT PRI 1 80 Xfer Cap.:SPEECH
16:19:26 ISDN.L2_FMT PRI 1 90 Xfer Rate:64k
16:19:26 ISDN.L2_FMT PRI 1 A2 Layer 1:u-Law
16:19:26 ISDN.L2_FMT PRI 1 IE - 18 CHANNEL ID Len=3
16:19:26 ISDN.L2_FMT PRI 1 A9 Primary Rate
16:19:26 ISDN.L2_FMT PRI 1 Intfc ID:IMPLICIT
16:19:26 ISDN.L2_FMT PRI 1 Pref/Excl:EXCLUSIVE
16:19:26 ISDN.L2_FMT PRI 1 D-Chan Indicated:NO
16:19:26 ISDN.L2_FMT PRI 1 Chan. Sel:FOLLOWS
16:19:26 ISDN.L2_FMT PRI 1 83 Numb/Map:NUMBER
16:19:26 ISDN.L2_FMT PRI 1 81 Channel:1
16:19:26 ISDN.L2_FMT PRI 1 =====================================================
16:19:26 ISDN.L2_FMT PRI 1 Sent = Sapi:00 C/R:C Tei:00
16:19:26 ISDN.L2_FMT PRI 1 Ctl:INFO Ns:41 Nr:43
16:19:26 ISDN.L2_FMT PRI 1 Prot:08 CRL:2 CRV:801E
16:19:26 ISDN.L2_FMT PRI 1 M - 5A RELEASE_CMP
16:19:26 ISDN.L2_FMT PRI 1 IE - 08 CAUSE Len=3
16:19:26 ISDN.L2_FMT PRI 1 82 Location:LN
16:19:26 ISDN.L2_FMT PRI 1 E0 Cause:96 (MANDATORY_IE_MISSING)
16:19:26 ISDN.L2_FMT PRI 1 70 Diagnostic:

 

[tlpeter]

How are your shortcodes programmed?
Answer the questions or solved it yourself please!!!
We cannot help you if you do not answer the questions.


BAZINGA!

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

 

[jplissonneau]

> 16:19:26 ISDN.L2_FMT PRI 1 E0 Cause:96 (MANDATORY_IE_MISSING)

There. IE stands for "Information Element", excepted that there are many of them and it doesn't tell you which missing one is frustrating your CO. It would be nice to have the lower level trace (the hex dump) as well as which number you tried to call.

If you do not see you correct called number there, you definitely have to fix your short codes / ARS tables. Here it looks like you are trying to setup a call without transmitting any destination number at all.

If it's ok, then what's your country, and what kind of switch/protocol your provider told you to use?


--
A(PS|CI)S-SMEC
IT consultant
Canada / Quebec City area

 

[Greybeard191]

another issue you might be having may come from the type of telco switch you're attached to... I know that if you're attached to a GTD5, you need to do what is described in technical tip 212, for example. Was a private build back in the day, but still is required under R7.

Contents of tech tip 212 below:

This Technical Tip is to advise customers of a potential issue with T1/PRI lines configured for NI2 that may occur on upgrades or new installations with IP Office releases 4.1(15) and 4.2(4).

It has been observed that T1/PRI line configurations may not accept a called party type set to “National Number”. Certain NI2 line configurations are set to accept only called party type “Unknown Numbering Plan". The network provider may not process calls with the "National Number" format, rejecting the call setup as an invalid number format. This results in the inability to initiate outbound calls.

Avaya has produced private build 4.2(49601) to resolve the issue by providing the ability to adjust the called party type to "Unknown Numbering Plan". After installation of the private build you must activate the function by adding the following string into the NoUser Source Numbers tab and then merging the change into the configuration:

NI2_CALLED_PARTY_TYPE=UNKNOWN

 

[jplissonneau]

@Greybeard191

Thanks a lot for the tip because I totally need that for my setup of the week, and felt bad about not setting the switch type to NI2 as it should be. GTD5. Yuck.


--
A(PS|CI)S-SMEC
IT consultant
Canada / Quebec City area

 

[iedatamaster]

What should be the ARS Table for a simple T1/Pri config...... I belive that is the actual issue too...

also where can i download this private build.

 

[piethief]

Private builds, unless made public(rarely) only come from Avaya. If it's not on the support site under downloads. Contact the vendor, unless you are a vendor, then contact the distributor. These systems are meant to be administered by professionals.

 

[iedatamaster]

Well here is the ARS route that was set up.

I support NBX and Cisco Call Managers.

The AVAYA system i inherited is beyond confusing.

 

[piethief]

As long as the PRI channels are line group 0 it should work. Have the carrier confirm all settings on the PRI line. Check the System Status Applications to see what error codes you receive from them. Bring them to their attention. From my experience it is something on their end.

Avaya has made this system extremely easy to administer and troubleshoot. Of the 20 or so types of systems I service, this is my dream machine.If you are having problems I would advise bringing in someone else who knows what to look for and how to communicate it to the carrier.

I don't doubt your skills on the systems you know, but these are general settings that should have been established and agreed upon before migrating to a new circuit. If the carrier said it would work, they need to work with you to get it working.

 

[amriddle01]

iedatamaster, how you can work on Call damager and call this system confusing is beyond me. There are few settings that are not self explanitory, but short codes being one of them. That ARS table looks correct but that only matters if the system shortcodes and the actual configuration of the PRI is correct also.

I will confirm the rest is correct if you want to send a copy of the config (.cfg file) to the works address from the link in my signature below. Edit any sensitive/non relevant info, if you don't feel safe doing so fair enough

http://www.ipoffice.tel

 

[iedatamaster]

UNICODE-UTF8
enu

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

********** SysMonitor v8.1 (5) **********

********** contact made with 192.168.1.107 at 15:19:18 11/7/2011 **********

********** System (192.168.1.107) has been up and running for 28secs(28719mS) **********

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


********** Warning: TEXT Logging to File STOPPED on 11/7/2011 15:19:18 **********
28719mS PRN: Monitor Started IP=192.168.1.108 IP 406 DS 4.2(4) 00E007027C62
(IP Office: Supports Unicode, System Locale is enu)
28719mS PRN: LAW=U PRI=1, BRI=0, ALOG=4, ADSL=0 VCOMP=0, MDM=0, WAN=0, MODU=1 LANM=0 CkSRC=0 VMAIL=0(VER=0 TYP=1) CALLS=0(TOT=0)
28930mS RES: Mon 11/7/2011 15:19:17 FreeMem=45350640(2) CMMsg=2 (3) Buff=100 609 500 1052 5 Links=1758
28931mS RES2: RTEngine=0, CMRTEngine=0, Timer=62, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
29464mS PRN: Sorting User List...
29465mS PRN: User List Sorted
29467mS PRN: Sorting Hunt Group List...
29468mS PRN: Hunt Group List Sorted
29470mS PRN: Sorting Directory List...
29470mS PRN: Sorted Directory List.
29471mS PRN: Created Tone MOH Source 1
29472mS PRN: Playing tone MOH Source 1
29473mS PRN: Config Write Completed
30592mS CMExtnEvt: Extn203: ExtnFault now 0
30595mS PRN: ObjectMemoryPool: SSIComponentId, filled 5 times
30597mS CMExtnTx: v=203, p1=0xxxxxxxxxx
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Extn203 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn203 Msgs=0]
Timed: 11/07/11 15:19
30685mS CMExtnEvt: Extn210: ExtnFault now 0
30690mS CMExtnTx: v=210, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Extn210 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn210 Msgs=0]
Timed: 11/07/11 15:19
30702mS CMExtnEvt: Extn207: ExtnFault now 0
30707mS CMExtnTx: v=207, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Extn207 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn207 Msgs=0]
Timed: 11/07/11 15:19
30719mS CMExtnEvt: Extn204: ExtnFault now 0
30723mS CMExtnTx: v=204, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Extn204 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn204 Msgs=0]
Timed: 11/07/11 15:19
30727mS CMExtnEvt: Extn206: ExtnFault now 0
30731mS CMExtnTx: v=206, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Extn206 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn206 Msgs=0]
Timed: 11/07/11 15:19
30740mS CMExtnEvt: Extn205: ExtnFault now 0
30745mS CMExtnTx: v=205, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Extn205 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn205 Msgs=0]
Timed: 11/07/11 15:19
30748mS CMExtnEvt: Extn208: ExtnFault now 0
30753mS CMExtnTx: v=208, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Extn208 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn208 Msgs=0]
Timed: 11/07/11 15:19
33439mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=4,p4=127,s1=
33439mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
33440mS CMLineRx: v=1
CMLineDown
Line: type=Q931Line 1 Call: lid=0 id=-1 in=0
33440mS CMLineTx: v=1
CMRestart
Line: type=Q931Line 1 Call: lid=0 id=-1 in=0
33448mS CMTARGET: CMQ931Trunk:reprocessInboundMsg - failure_timer set
33929mS RES: Mon 11/7/2011 15:19:22 FreeMem=45157012(1) CMMsg=2 (3) Buff=100 609 500 1051 5 Links=1137
33929mS RES2: RTEngine=0, CMRTEngine=0, Timer=50, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
37449mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
38213mS ISDNL1Evt: v=1 peb=1,F1 F2
38263mS ISDNL1Evt: v=1 peb=1,PHAI
38442mS ISDNL1Evt: v=1 peb=1,Locked
38442mS PRN: WARNING:
38442mS PRN: LockToFalc 1, CLK_CTL 0x04
38442mS PRN:
38442mS PRN: LockToFalc 1, CLK_CTL 0x04
39287mS ISDNL3Evt: v=1 p1=1,p2=1000,p3=0,p4=0,s1=
39289mS CMLineRx: v=1
CMLineUp
Line: type=Q931Line 1 Call: lid=0 id=-1 in=0
48688mS PRN: 208: Digit Pressed 9
48689mS CMCallEvt: 0.1002.0 -1 BaseEP: NEW CMEndpoint feb0eea8 TOTAL NOW=1 CALL_LIST=0
48689mS CMCallEvt: CREATE CALL:1 (feb0ea98)
48690mS CMCallEvt: 0.1003.0 -1 BaseEP: NEW CMEndpoint feb0dabc TOTAL NOW=2 CALL_LIST=0
48694mS CMExtnEvt: Extn208: CMExtnHandler::SetCurrent( id: 0->1002 )
48694mS CMExtnRx: v=208, p1=0
CMSetup
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[208] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn208
48694mS CMCallEvt: 0.1002.0 1 Extn208.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
48694mS CMExtnEvt: v=6 State, new=Connected old=Idle,0,0,Extn208
48695mS CMTARGET: 0.1002.0 1 Extn208.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=208 dir=out complete=0 ses=0
48695mS CMTARGET: 0.1002.0 1 Extn208.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
48696mS CMCallEvt: 0.1002.0 1 Extn208.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
48697mS CMExtnEvt: v=6 State, new=Dialling old=Connected,0,0,Extn208
48697mS CMExtnTx: v=208, p1=0
CMSetupAck
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
BChan: slot=1 chan=15
Timed: 11/07/11 15:19
48697mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
48698mS CMTARGET: 0.1002.0 1 Extn208.0: Setting Hard Timer 3000
48698mS CMTARGET: 0.1002.0 1 Extn208.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=208 dir=out complete=0 ses=0
48699mS CMTARGET: 0.1002.0 1 Extn208.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
48699mS CMTARGET: 0.1002.0 1 Extn208.0: SYS SC: 9 2 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
48700mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=50
48700mS CMARS: CMARSHandler::MakeCallTarget - Called Number:
48700mS CMCallEvt: 0.1004.0 -1 BaseEP: NEW CMEndpoint feb0xxxxxxxxxx64 TOTAL NOW=3 CALL_LIST=1
48701mS CMTARGET: 0.1002.0 1 Extn208.0: CancelTimer CMTCDelayedProcessing
48701mS CMTARGET: 0.1002.0 1 Extn208.0: INITIAL TARGETING SUCCEEDED
48701mS CMTARGET: 0.1002.0 1 Extn208.0: GetNoAnswerTimer:15
48702mS CMCallEvt: 0.1003.0 1 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
48703mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
48704mS CMCallEvt: 0.1003.0 1 TargetingEP: RequestEnd 0.1004.0 1 ARS for Main
48704mS CMTARGET: 0.1002.0 1 Extn208.0: CancelTimer CMTCNoAnswerTimeout
48704mS CMCallEvt: 0.1004.0 1 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
48705mS CMARS: FORM: Main - Received Number:
48705mS CMARS: CMARSTargetingPending
48705mS CMCallEvt: 0.1003.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
48706mS CMCallEvt: 0.1003.0 -1 BaseEP: DELETE CMEndpoint feb0dabc TOTAL NOW=2 CALL_LIST=1
48707mS CD: CALL: 0.1002.0 BState=Idle Cut=2 Music=3.0 Aend="Extn208(208)" (1.15) Bend="" [] (0.0) CalledNum= () CallingNum=208 (Extn208) Internal=1 Time=17 AState=Dialling
48708mS CMMap: a=1.15 b=0.0 D1
48928mS RES: Mon 11/7/2011 15:19:37 FreeMem=45114840(1) CMMsg=4 (5) Buff=100 609 500 1052 5 Links=681
48928mS RES2: RTEngine=0, CMRTEngine=0, Timer=49, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
50511mS PRN: 208: Digit Pressed x
50511mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
50512mS CMARS: FORM: Main - Received Number: 1
50512mS CMARS: CMARSTargetingPending
50513mS CMMap: a=1.15 b=0.0 D0
51229mS PRN: 208: Digit Pressed x
51230mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
51230mS CMARS: FORM: Main - Received Number: 15
51231mS CMARS: CMARSTargetingPending
51409mS PRN: 208: Digit Pressed x
51410mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[6] Type=Default (100) Reason=CMDRdirect
51410mS CMARS: FORM: Main - Received Number: 1xxxxxxxxxx
51410mS CMARS: CMARSTargetingPending
51610mS PRN: 208: Digit Pressed x
51610mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
51611mS CMARS: FORM: Main - Received Number: 1xxxxxxxxxx
51611mS CMARS: CMARSTargetingPending
52530mS PRN: 208: Digit Pressed x
52530mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
52531mS CMARS: FORM: Main - Received Number: 1xxxxxxxxxx
52531mS CMARS: CMARSTargetingPending
52709mS PRN: 208: Digit Pressed x
52709mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
52710mS CMARS: FORM: Main - Received Number: 1xxxxxxxxxx
52710mS CMARS: CMARSTargetingPending
52880mS PRN: 208: Digit Pressed x
52880mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
52881mS CMARS: FORM: Main - Received Number: 1xxxxxxxxxx
52881mS CMARS: CMARSTargetingPending
53110mS PRN: 208: Digit Pressed x
53111mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
53111mS CMARS: FORM: Main - Received Number: 1xxxxxxxxxx
53111mS CMARS: CMARSTargetingPending
53334mS PRN: 208: Digit Pressed x
53334mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[8] Type=Default (100) Reason=CMDRdirect
53335mS CMARS: FORM: Main - Received Number: 1xxxxxxxxxx
53335mS CMARS: CMARSTargetingPending
53542mS PRN: 208: Digit Pressed x
53543mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
53543mS CMARS: FORM: Main - Received Number: 1xxxxxxxxxx
53543mS CMARS: CMARSTargetingPending
53730mS PRN: 208: Digit Pressed x
53731mS CMExtnRx: v=208, p1=0
CMInformation
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
53732mS CMARS: FORM: Main - Received Number: 1xxxxxxxxxx
53732mS CMARS: CMARSTargetingPending
57701mS CMARS: Case 1 - dial_delay_time: 3000 has expired - Resolve what we might have - Called Number: 1xxxxxxxxxx Addr: feb0xxxxxxxxxx64
57702mS CMARS: FORM: Main - Received Number: 1xxxxxxxxxx
57702mS CMARS: FOUND A SHORT CODE - short_code: 1N; - Tel: 1N - Called_Party: 1xxxxxxxxxx - Line Group Id: 101
57702mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=101
57703mS CMLRQ: CMLRQHandler::FindActiveLRQByGroupID GroupID=101
57703mS CMCallEvt: 0.1005.0 -1 BaseEP: NEW CMEndpoint feb03df0 TOTAL NOW=3 CALL_LIST=1
57707mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 101 - Called Number: 1xxxxxxxxxx - Calling Number: 208
57707mS CMARS: SEND Setup TO LINE
57708mS CMCallEvt: 0.1005.0 1 Q931 Trunk:1 CHAN=8: StateChange: END=child CMCSIdle->CMCSOffering
57708mS PRN: Setting configured voice gain for ch 8.
57708mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
Called[1xxxxxxxxxx] Type=National (2) Reason=CMDRdirect Calling[] Type=National Plan=ISDN
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=8
Locale: enu
57709mS CD: CALL: 0.1002.0 BState=Idle Cut=2 Music=0.0 Aend="Extn208(208)" (1.15) Bend="" [Line 1] (0.0) CalledNum=1xxxxxxxxxx () CallingNum=208 (Extn208) Internal=1 Time=9020 AState=Dialling
57711mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=1005
57730mS ISDNL3Evt: v=1 stacknum=1 State, new=Proceeding, old=Initiated id=1005
57732mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
BChan: slot=0 chan=8
57732mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
57732mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
57733mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.1004.0 1 ARS for Main - Call State: CMCSOverlapRecv
57733mS CMCallEvt: 0.1004.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
57734mS CMCallEvt: 0.1005.0 1 Q931 Trunk:1 CHAN=8: StateChange: END=B CMCSOffering->CMCSAccept
57734mS CMCallEvt: 0.1002.0 1 Extn208.0: StateChange: END=A CMCSDialling->CMCSDialled
57735mS CMExtnEvt: v=6 State, new=Proceeding old=Dialling,0,0,Extn208
57735mS CMExtnTx: v=208, p1=0
CMProceeding
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=91xxxxxxxxxx
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceISDNTrunk type=2 number=1 channel=8 rx_gain=32 tx_gain=32 ep_callid=1005 ipaddr=192.168.1.107 apps=0
Timed: 11/07/11 15:19
57735mS CMCallEvt: 0.1004.0 -1 BaseEP: DELETE CMEndpoint feb0xxxxxxxxxx64 TOTAL NOW=2 CALL_LIST=1
57736mS CD: CALL: 0.1002.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn208(208)" (1.15) Bend="Line 1" [Line 1] (2.9) CalledNum=1xxxxxxxxxx () CallingNum=208 (Extn208) Internal=0 Time=9047 AState=Dialled
57738mS CMMap: PCG::MapBChan pcp[95]b1r0 cp_b feb3fbf0 other_cp_b 0 type CGTypeSimple
57738mS CMMap: PCG::MapBChan pcp[66]b1r0 cp_b fee1dd14 other_cp_b feb3fbf0 type CGTypeSimple
57738mS CMMap: a=1.15 b=2.9 M1
57810mS ISDNL3Evt: v=1 stacknum=1 State, new=ReleaseReq, old=Proceeding id=1005
57829mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=ReleaseReq id=1005
57831mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=1005 in=0
BChan: slot=0 chan=8
Cause=21, Call rejected
57831mS CMCallEvt: 0.1005.0 1 Q931 Trunk:1 CHAN=8: StateChange: END=B CMCSAccept->CMCSCompleted
57834mS CMLOGGING: CALL:2011/07/1115:19,00:00:00,000,208,O,1xxxxxxxxxx,91xxxxxxxxxx,Extn208,,,0,,""n/a,0
57834mS CD: CALL: 0.1002.0 BState=Disconnecting Cut=1 Music=0.0 Aend="Extn208(208)" (1.15) Bend="Line 1" [Line 1] (2.9) CalledNum=1xxxxxxxxxx () CallingNum=208 (Extn208) Internal=0 Time=9145 AState=Dialled
57834mS CD: CALL: 0.1002.0 Deleted
57835mS CMExtnEvt: Extn208: CALL LOST (CMCauseCallRejected)
57836mS CMExtnEvt: Extn208: Extn(208) Calling Party Number(208) Type(CMNTypeInternal)
57836mS CMCallEvt: 0.1002.0 -1 Extn208.0: StateChange: END=X CMCSDialled->CMCSCompletedTone
57836mS CMExtnEvt: v=6 State, new=CMESCompleted old=Proceeding,0,0,Extn208
57837mS CMCallEvt: 0.1005.0 -1 Q931 Trunk:1 CHAN=8: StateChange: END=X CMCSCompleted->CMCSDelete
57837mS CMCallEvt: END CALL:1 (feb0ea98)
57839mS CMCallEvt: 0.1005.0 -1 BaseEP: DELETE CMEndpoint feb03df0 TOTAL NOW=1 CALL_LIST=0
57841mS CMMap: PCG::UnmapBChan pcp[95]b1r0 cp_b feb3fbf0 other_cp_b fee1dd14
57841mS CMMap: a=2.9 b=1.15 M0
57841mS CMMap: PCG::UnmapBChan pcp[66]b1r0 cp_b fee1dd14 other_cp_b 0
57841mS CMMap: a=5.6 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
57842mS CMMap: a=5.6 b=1.255 T
57842mS CMMap: a=5.6 b=1.15 M2
57928mS RES: Mon 11/7/2011 15:19:46 FreeMem=45120628(1) CMMsg=4 (5) Buff=100 601 500 1052 5 Links=442
57929mS RES2: RTEngine=0, CMRTEngine=0, Timer=49, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
59478mS PRN: Created WAV file MOH Source 1
59505mS PRN: Loading HoldMusic.wav from ipaddr=255.255.255.255
59549mS PRN: WAV Hold Music HoldMusic.wav failed to load
59550mS PRN: Deleted WAV file MOH Source 1
61745mS LIC: Rainbow token file not received/invalid from 255.255.255.255.
71867mS CMExtnRx: v=208, p1=0
CMReleaseComp
Line: type=DigitalExtn 3 Call: lid=0 id=1002 in=0
71867mS CMCallEvt: 0.1002.0 -1 Extn208.0: StateChange: END=X CMCSCompletedTone->CMCSCompleted
71868mS CMExtnEvt: Extn208: CALL LOST (CMCauseForceClear)
71868mS CMExtnEvt: Extn208: Extn(208) Calling Party Number(208) Type(CMNTypeInternal)
71868mS CMExtnEvt: Extn208: CMExtnHandler::SetCurrent( id: 1002->0 )
71869mS CMCallEvt: 0.1002.0 -1 Extn208.-1: StateChange: END=X CMCSCompleted->CMCSDelete
71869mS CMExtnEvt: v=6 State, new=PortRecoverDelay old=CMESCompleted,0,0,Extn208
71869mS CMTARGET: 0.1002.0 -1 BaseEP: ~CMTargetHandler
71870mS CMCallEvt: 0.1002.0 -1 BaseEP: DELETE CMEndpoint feb0eea8 TOTAL NOW=0 CALL_LIST=0
71871mS CMMap: a=5.6 b=1.15 M0
71872mS CMMap: a=5.6 b=0.0 T0
71872mS CMMap: a=5.6 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
71928mS RES: Mon 11/7/2011 15:20:00 FreeMem=45134760(2) CMMsg=4 (5) Buff=100 609 500 1052 5 Links=457
71929mS RES2: RTEngine=0, CMRTEngine=0, Timer=49, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
73869mS CMExtnEvt: Extn208: Recover Timer reason=CMTRWrapUp
73870mS CMExtnEvt: v=6 State, new=Idle old=PortRecoverDelay,0,0,Extn208
73870mS CMExtnTx: v=208, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Extn208 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn208 Msgs=0]
Timed: 11/07/11 15:20

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