I am with you on the idea of using just a short code as opposed to ARS. We did try this previously, but reverted back to using ARS codes because of the local area code dialing problem.
I am willing to make another test short code to try this out, unfortunately, all our line ID's are the same (default), changing them for testing looks like a change that is non-mergeable and i can't reboot the IPO during business hours.
Sorry if i'm using the wrong terminology, over the years, i've been forced into becoming some sort of an Avaya tech even though i have no formal training!
Here is the whole log which should have the trace all the way to the disconnect: (Sorry there were some calls coming into our Hunt Group that may have made the log a bit cluttered)
********** Warning: TEXT Logging to File STARTED on 6/1/2010 11:14:32 **********
575372212mS PRN: Monitor Started IP=100.10.10.91 IP 406 DS 4.2(4) CEVision406v2
(IP Office: Supports Unicode, System Locale is enu)
575372212mS PRN: LAW=U PRI=2, BRI=0, ALOG=0, ADSL=0 VCOMP=8, MDM=0, WAN=0, MODU=3 LANM=0 CkSRC=1 VMAIL=1(VER=3 TYP=1) CALLS=11(TOT=5916)
575372707mS RES: Wed 6/1/2010 11:14:31 FreeMem=43021572(1) CMMsg=6 (6) Buff=100 582 500 1010 4 Links=4361
575372707mS RES2: RTEngine=0, CMRTEngine=0, Timer=69, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
575372807mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575372808mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575372808mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575372809mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575372809mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575372809mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575373620mS CMTARGET: 0.25019.0 5916 Accounting Fax.0: TimerExpired cause=CMTCDelayedProcessing
575373620mS CMTARGET: 0.25019.0 5916 Accounting Fax.0: LOOKUP CALL ROUTE: type=100 called_party=713108264896 sub= calling=257 dir=out complete=1 ses=0
575373621mS CMTARGET: 0.25019.0 5916 Accounting Fax.0: ADD TARGET (N): number=713108264896 type=100 depth=1 nobar=1 setorig=1 ses=0
575373621mS CMTARGET: 0.25019.0 5916 Accounting Fax.0: SYS SC: 713108264896 3 13108264896 sc=type=Dial code=7N;, num=N callinfop->sending_complete=1 secondary_dialtone=
575373622mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=50
575373622mS CMARS: CMARSHandler::MakeCallTarget - Called Number: 13108264896
575373623mS CMTARGET: 0.25019.0 5916 Accounting Fax.0: INITIAL TARGETING SUCCEEDED
575373623mS CMTARGET: 0.25019.0 5916 Accounting Fax.0: GetNoAnswerTimer:15
575373624mS CMExtnTx: v=257, p1=0
CMProceeding
Line: type=AnalogueExtn 6 Call: lid=0 id=1 in=0
BChan: slot=12 chan=3
Timed: 06/01/10 11:14
575373625mS CMTARGET: 0.25019.0 5916 Accounting Fax.0: CancelTimer CMTCNoAnswerTimeout
575373626mS CMARS: FORM: [7] - Received Number: 13108264896
575373626mS CMARS: FOUND A SHORT CODE - short_code: 1N; - Tel: 1N - Called_Party: 13108264896 - Line Group Id: 0
575373627mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=0
575373627mS CMLRQ: CMLRQHandler::FindActiveLRQByGroupID GroupID=0
575373634mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 13108264896 - Calling Number: 257
575373634mS CMARS: SEND Setup TO LINE
575373635mS PRN: Setting configured voice gain for ch 23.
575373635mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=25022 in=0
Called[13108264896] Type=National (2) Reason=CMDRdirect Calling[9493612371] Type=National Plan=ISDN Pres=AllowedIndSend (101)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=23
Locale: enu
575373637mS CD: CALL: 0.25019.0 BState=Idle Cut=3 Music=0.0 Aend="Accounting Fax(257)" (12.3) Bend="" [Line 1] (0.0) CalledNum=13108264896 () CallingNum=257 (Accounting Fax) Internal=1 Time=7005 AState=Dialled
575373642mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=25022
575373643mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0052(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 90 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 97 .....
InformationElement = CallingPartyNumber
0000 6c 0c 21 80 39 34 39 33 36 31 32 33 37 31 l.!.9493612371
InformationElement = CalledPartyNumber
0000 70 0c a0 31 33 31 30 38 32 36 34 38 39 36 p..13108264896
575373809mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575373810mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575373810mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575373811mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575373811mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575373811mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575373931mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0052(Local)
Message Type = CallProceeding
InformationElement = CHI
0000 18 03 a9 83 97 .....
575373931mS ISDNL3Evt: v=1 stacknum=1 State, new=Proceeding, old=Initiated id=25022
575373934mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=25022 in=0
BChan: slot=0 chan=23
575373934mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
575373934mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
575373935mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.25021.0 5916 ARS for [7] - Call State: CMCSOverlapRecv
575373939mS CD: CALL: 0.25019.0 BState=Ringing Cut=3 Music=0.0 Aend="Accounting Fax(257)" (12.3) Bend="Line 1" [Line 1] (2.24) CalledNum=13108264896 () CallingNum=257 (Accounting Fax) Internal=0 Time=7307 AState=Dialled
575373941mS CMMap: PCG::MapBChan pcp[115]b1r0 cp_b fed0316c other_cp_b 0 type CGTypeSimple
575373942mS CMMap: PCG::MapBChan pcp[89]b1r0 cp_b fed6ff94 other_cp_b fed0316c type CGTypeSimple
575373942mS CMMap: a=12.3 b=2.24 M1
575374759mS CMExtnRx: v=193, p1=0
CMSetup
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[193] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110) AEkinaka
IE CMIECallingPartyKName (226) Aaron Ekinaka
575374761mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=193 dir=out complete=0 ses=0
575374761mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
575374763mS CMExtnTx: v=193, p1=0
CMSetupAck
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
BChan: slot=10 chan=21
Timed: 06/01/10 11:14
575374764mS CD: CALL: 0.25023.0 BState=Idle Cut=1 Music=3.0 Aend="AEkinaka(193)" (10.21) Bend="" [] (0.0) CalledNum= () CallingNum=193 (AEkinaka) Internal=1 Time=10 AState=DialInitiated
575374766mS CMMap: a=10.21 b=0.0 D1
575374776mS CD: CALLSYNC: cs02
575374811mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575374812mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575374812mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575374813mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575374813mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575374813mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575375814mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575375814mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575375814mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575375815mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575375815mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575375815mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575376073mS PRN: 193: Digit Pressed 7
575376074mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
575376075mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575376075mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=7 sub= calling=193 dir=out complete=0 ses=0
575376076mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=7 type=100 depth=1 nobar=1 setorig=1 ses=0
575376079mS CMMap: a=10.21 b=0.0 D0
575376473mS PRN: 193: Digit Pressed 0
575376474mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
575376475mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575376475mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=70 sub= calling=193 dir=out complete=0 ses=0
575376475mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=70 type=100 depth=1 nobar=1 setorig=1 ses=0
575376689mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0052(Local)
Message Type = Alerting
575376690mS ISDNL3Evt: v=1 stacknum=1 State, new=Delivered, old=Proceeding id=25022
575376692mS CMLineRx: v=1
CMAlerting
Line: type=Q931Line 1 Call: lid=0 id=25022 in=0
575376694mS CMExtnTx: v=257, p1=0
CMAlerting
Line: type=AnalogueExtn 6 Call: lid=0 id=1 in=0
BChan: slot=12 chan=3
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=713108264896
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceISDNTrunk type=2 number=1 channel=23 rx_gain=32 tx_gain=32 ep_callid=25022 ipaddr=100.10.10.90 apps=0
Timed: 06/01/10 11:14
575376696mS CD: CALL: 0.25019.0 BState=Ringing Cut=3 Music=0.0 Aend="Accounting Fax(257)" (12.3) Bend="Line 1" [Line 1] (2.24) CalledNum=13108264896 () CallingNum=257 (Accounting Fax) Internal=0 Time=10064 AState=Ringing
575376815mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575376816mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575376816mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575376817mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575376817mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575376817mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575377099mS PRN: 193: Digit Pressed 1
575377100mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
575377101mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575377101mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=701 sub= calling=193 dir=out complete=0 ses=0
575377102mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=701 type=100 depth=1 nobar=1 setorig=1 ses=0
575377362mS PRN: 193: Digit Pressed 1
575377363mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
575377364mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575377364mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=7011 sub= calling=193 dir=out complete=0 ses=0
575377364mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=7011 type=100 depth=1 nobar=1 setorig=1 ses=0
575377818mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575377819mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575377819mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575377819mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575377820mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575377820mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575378137mS PRN: 193: Digit Pressed 3
575378138mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
575378139mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575378139mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=70113 sub= calling=193 dir=out complete=0 ses=0
575378140mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=70113 type=100 depth=1 nobar=1 setorig=1 ses=0
575378492mS CMExtnRx: v=574, p1=0
CMReleaseComp
Line: type=DigitalExtn 4 Call: lid=0 id=24984 in=0
575378498mS CMLOGGING: CALL:2010/01/0611:11,00:02:31,009,574,O,15036250733,715036250733,SCamu,,,0,,""n/a,0
575378499mS CD: CALL: 0.24984.0 BState=Connected Cut=2 Music=0.0 Aend="SCamu(574)" (10.22) Bend="Line 2" [Line 2] (8.22) CalledNum=15036250733 () CallingNum=574 (SCamu) Internal=0 Time=173444 AState=Idle
575378501mS CD: CALL: 0.24984.0 Deleted
575378510mS CMLineTx: v=2
CMDisconnect
Line: type=Q931Line 2 Call: lid=0 id=24987 in=0
Cause=16, Normal call clearing
575378516mS CMMap: PCG::UnmapBChan pcp[110]b1r0 cp_b fed50b60 other_cp_b fea23894
575378516mS CMMap: a=10.22 b=8.22 M0
575378517mS CMMap: PCG::UnmapBChan pcp[151]b1r0 cp_b fea23894 other_cp_b 0
575378518mS ISDNL3Evt: v=2 stacknum=2 State, new=DiscReq, old=Active id=24987
575378518mS ISDNL3Tx: v=2 peb=2
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0052(Remote)
Message Type = Disconnect
InformationElement = CAUSE
0000 08 02 80 90 ....
575378519mS PRN: 193: Digit Pressed 9
575378520mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
575378521mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575378521mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=70422 sub= calling=193 dir=out complete=0 ses=0
575378522mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=701139 type=100 depth=1 nobar=1 setorig=1 ses=0
575378532mS CD: CALLSYNC: cs02
575378599mS ISDNL3Rx: v=2 peb=2
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0052(Local)
Message Type = Release
InformationElement = CAUSE
0000 08 02 82 90 ....
575378600mS ISDNL3Tx: v=2 peb=2
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0052(Remote)
Message Type = ReleaseComplete
575378601mS ISDNL3Evt: v=2 stacknum=2 State, new=NullState, old=DiscReq id=24987
575378602mS CMLineRx: v=2
CMReleaseComp
Line: type=Q931Line 2 Call: lid=0 id=24987 in=0
BChan: slot=0 chan=21
Cause=16, Normal call clearing
575378708mS RES: Wed 6/1/2010 11:14:37 FreeMem=43017048(1) CMMsg=6 (6) Buff=100 582 497 1010 4 Links=4363
575378708mS RES2: RTEngine=0, CMRTEngine=0, Timer=70, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
575378820mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575378821mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575378821mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575378822mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575378822mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575378822mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575379411mS PRN: 193: Digit Pressed 0
575379412mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
575379413mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575379413mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=7011390 sub= calling=193 dir=out complete=0 ses=0
575379413mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=7011390 type=100 depth=1 nobar=1 setorig=1 ses=0
575379504mS CMExtnRx: v=552, p1=0
CMReleaseComp
Line: type=DigitalExtn 4 Call: lid=0 id=24838 in=0
575379510mS CMLOGGING: CALL:2010/01/0611:04,00:10:13,008,7122248773,I,552,2440,,,,0,,""n/a,0
575379511mS CD: CALL: 2.20.1 BState=Disconnecting Cut=1 Music=0.0 Aend="Line 2" (8.3) Bend="JAustin(552)" [JAustin(552)] (10.27) CalledNum=552 (JAustin) CallingNum=7122248773 () Internal=0 Time=614124 AState=Connected
575379513mS CD: CALL: 2.20.1 Deleted
575379520mS CMLineTx: v=2
CMDisconnect
Line: type=Q931Line 2 Call: lid=0 id=20 in=1
Cause=16, Normal call clearing
575379528mS CMMap: PCG::UnmapBChan pcp[91]b1r0 cp_b fed5223c other_cp_b fea2b650
575379528mS CMMap: a=10.27 b=8.3 M0
575379529mS CMMap: PCG::UnmapBChan pcp[138]b1r0 cp_b fea2b650 other_cp_b 0
575379531mS ISDNL3Evt: v=2 stacknum=2 State, new=DiscReq, old=Active id=20
575379531mS ISDNL3Tx: v=2 peb=2
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=050C(Local)
Message Type = Disconnect
InformationElement = CAUSE
0000 08 02 80 90 ....
575379539mS CD: CALLSYNC: cs02
575379619mS ISDNL3Rx: v=2 peb=2
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=050C(Remote)
Message Type = Release
InformationElement = CAUSE
0000 08 02 82 90 ....
575379620mS ISDNL3Tx: v=2 peb=2
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=050C(Local)
Message Type = ReleaseComplete
575379621mS ISDNL3Evt: v=2 stacknum=2 State, new=NullState, old=DiscReq id=20
575379622mS CMLineRx: v=2
CMReleaseComp
Line: type=Q931Line 2 Call: lid=2 id=20 in=1
BChan: slot=0 chan=2
Cause=16, Normal call clearing
575379822mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575379822mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575379823mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575379823mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575379824mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575379824mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575380289mS PRN: 193: Digit Pressed 4
575380290mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[4] Type=Default (100) Reason=CMDRdirect
575380290mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575380291mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=70113904 sub= calling=193 dir=out complete=0 ses=0
575380291mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=70113904 type=100 depth=1 nobar=1 setorig=1 ses=0
575380664mS PRN: 193: Digit Pressed 2
575380665mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
575380666mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575380667mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=701139042 sub= calling=193 dir=out complete=0 ses=0
575380667mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=701139042 type=100 depth=1 nobar=1 setorig=1 ses=0
575380824mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575380825mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575380825mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575380826mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575380826mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575380826mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575380918mS PRN: 193: Digit Pressed 2
575380919mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
575380920mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575380920mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=7011390422 sub= calling=193 dir=out complete=0 ses=0
575380921mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=7011390422 type=100 depth=1 nobar=1 setorig=1 ses=0
575381177mS PRN: 193: Digit Pressed 4
575381178mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[4] Type=Default (100) Reason=CMDRdirect
575381179mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575381179mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=70113904224 sub= calling=193 dir=out complete=0 ses=0
575381179mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=70113904224 type=100 depth=1 nobar=1 setorig=1 ses=0
575381370mS H323Evt: Recv: RegistrationRequest ac190311; Endpoints registered: 6; Endpoints in registration: 0
575381452mS PRN: 193: Digit Pressed 3
575381453mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
575381454mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575381454mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=701139042243 sub= calling=193 dir=out complete=0 ses=0
575381455mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=701139042243 type=100 depth=1 nobar=1 setorig=1 ses=0
575381523mS CMExtnTx: v=552, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 4 Call: lid=0 id=-1 in=0
Called[JAustin Msgs=2 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000002] Type=Default Plan=Default
Display [JAustin Msgs=2]
Timed: 06/01/10 11:14
575381674mS PRN: 193: Digit Pressed 3
575381675mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
575381676mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575381676mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=7011390422433 sub= calling=193 dir=out complete=0 ses=0
575381677mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=7011390422433 type=100 depth=1 nobar=1 setorig=1 ses=0
575381827mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575381827mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575381827mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575381828mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575381828mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575381828mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575382164mS PRN: 193: Digit Pressed 0
575382165mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
575382165mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575382166mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=70113904224330 sub= calling=193 dir=out complete=0 ses=0
575382166mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=70113904224330 type=100 depth=1 nobar=1 setorig=1 ses=0
575382501mS PRN: 193: Digit Pressed 1
575382502mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
575382503mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575382503mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=701139042243301 sub= calling=193 dir=out complete=0 ses=0
575382504mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=701139042243301 type=100 depth=1 nobar=1 setorig=1 ses=0
575382825mS PRN: 193: Digit Pressed 1
575382826mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
575382827mS CMTARGET: 0.25023.0 5917 AEkinaka.0: Setting Hard Timer 4000
575382827mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=7011390422433011 sub= calling=193 dir=out complete=0 ses=0
575382827mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=7011390422433011 type=100 depth=1 nobar=1 setorig=1 ses=0
575382831mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575382831mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575382831mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575382832mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575382832mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575382832mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575383503mS CMExtnTx: v=574, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 4 Call: lid=0 id=-1 in=0
Called[SCamu Msgs=0 Old=8 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [SCamu Msgs=0]
Timed: 06/01/10 11:14
575383833mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575383833mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575383834mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575383834mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575383835mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575383835mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575384835mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575384836mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575384836mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575384836mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575384837mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575384837mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575385837mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575385838mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575385838mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575385839mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575385839mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575385839mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575386799mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0052(Local)
Message Type = Connect
575386800mS ISDNL3Evt: v=1 stacknum=1 State, new=Active, old=Delivered id=25022
575386800mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0052(Remote)
Message Type = ConnectAck
575386802mS CMLineRx: v=1
CMConnect
Line: type=Q931Line 1 Call: lid=0 id=25022 in=0
BChan: slot=0 chan=23
575386803mS CMExtnTx: v=257, p1=0
CMConnect
Line: type=AnalogueExtn 6 Call: lid=0 id=1 in=0
BChan: slot=12 chan=3
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=713108264896
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceISDNTrunk type=2 number=1 channel=23 rx_gain=32 tx_gain=32 ep_callid=25022 ipaddr=100.10.10.90 apps=0
Timed: 06/01/10 11:14
575386804mS CMExtnRx: v=257, p1=0
CMConnectAck
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
575386804mS CMTARGET: 0.25019.0 5916 Accounting Fax.0: ~CMTargetHandler
575386806mS CMLineTx: v=1
CMConnectAck
Line: type=Q931Line 1 Call: lid=0 id=25022 in=0
BChan: slot=0 chan=23
575386807mS CD: CALL: 0.25019.0 BState=Connected Cut=3 Music=0.0 Aend="Accounting Fax(257)" (12.3) Bend="Line 1" [Line 1] (2.24) CalledNum=13108264896 () CallingNum=257 (Accounting Fax) Internal=0 Time=20176 AState=Connected
575386827mS CMTARGET: 0.25023.0 5917 AEkinaka.0: TimerExpired cause=CMTCDelayedProcessing
575386827mS CMTARGET: 0.25023.0 5917 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=7011390422433011 sub= calling=193 dir=out complete=1 ses=0
575386828mS CMTARGET: 0.25023.0 5917 AEkinaka.0: ADD TARGET (N): number=7011390422433011 type=100 depth=1 nobar=1 setorig=1 ses=0
575386829mS CMTARGET: 0.25023.0 5917 AEkinaka.0: SYS SC: 7011390422433011 3 011390422433011 sc=type=Dial code=7N;, num=N callinfop->sending_complete=1 secondary_dialtone=
575386829mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=50
575386830mS CMARS: CMARSHandler::MakeCallTarget - Called Number: 011390422433011
575386830mS CMTARGET: 0.25023.0 5917 AEkinaka.0: INITIAL TARGETING SUCCEEDED
575386830mS CMTARGET: 0.25023.0 5917 AEkinaka.0: GetNoAnswerTimer:15
575386831mS CMExtnTx: v=193, p1=0
CMProceeding
Line: type=DigitalExtn 4 Call: lid=0 id=25023 in=0
Timed: 06/01/10 11:14
575386833mS CMTARGET: 0.25023.0 5917 AEkinaka.0: CancelTimer CMTCNoAnswerTimeout
575386833mS CMARS: FORM: [7] - Received Number: 011390422433011
575386834mS CMARS: FOUND A SHORT CODE - short_code: 0N; - Tel: 0N - Called_Party: 011390422433011 - Line Group Id: 0
575386834mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=0
575386834mS CMLRQ: CMLRQHandler::FindActiveLRQByGroupID GroupID=0
575386857mS CMARS: FOUND LINE - Line Id: 2 - using line group id: 0 - Called Number: 011390422433011 - Calling Number: 193
575386858mS CMARS: SEND Setup TO LINE
575386858mS PRN: Setting configured voice gain for ch 23.
575386859mS CMLineTx: v=2
CMSetup
Line: type=Q931Line 2 Call: lid=0 id=25026 in=0
Called[011390422433011] Type=National (2) Reason=CMDRdirect Calling[9492722401] Type=National Plan=ISDN Pres=AllowedIndSend (101)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=23
Locale: enu
575386860mS CD: CALL: 0.25023.0 BState=Idle Cut=3 Music=0.0 Aend="AEkinaka(193)" (10.21) Bend="" [Line 2] (0.0) CalledNum=011390422433011 () CallingNum=193 (AEkinaka) Internal=1 Time=12106 AState=Dialled
575386864mS ISDNL3Evt: v=2 stacknum=2 State, new=Initiated, old=NullState id=25026
575386865mS ISDNL3Tx: v=2 peb=2
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0054(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 90 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 97 .....
InformationElement = CallingPartyNumber
0000 6c 0c 21 80 39 34 39 32 37 32 32 34 30 31 l.!.9492722401
InformationElement = CalledPartyNumber
0000 70 10 a0 30 31 31 33 39 30 34 32 32 34 33 33 30 p..0113904224330
0010 31 31 11
575386866mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: PrimeForHGTarget: RudyProject setorig=0 recall=0 resetExtnVars 1
575386866mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTarget RudyProject (depth=0) allowq=0 type=CMNTypeDefault
575386867mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: AddHGTargetRingIdle RudyProject ring_attempt_count 0
575386867mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: OV visable. VM NOT visable:Group VM off
575386867mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: FindNextInServiceHgInHgIncludedList: No Alternative!
575386867mS CMTARGET: 2.24.1 5911 Q931 Trunk:2 CHAN=5: ADD HG RudyProject Complete search - no targets found
575386919mS ISDNL3Rx: v=2 peb=2
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0054(Local)
Message Type = ReleaseComplete
InformationElement = CAUSE
0000 08 02 c0 88 ....
575386920mS ISDNL3Evt: v=2 stacknum=2 State, new=NullState, old=Initiated id=25026
575386922mS CMLineRx: v=2
CMReleaseComp
Line: type=Q931Line 2 Call: lid=0 id=25026 in=0
BChan: slot=0 chan=23
Cause=8, Preemption/(NI-2)Prefix 0 dialed in error
575386922mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
575386930mS CMLineTx: v=2
CMDisconnect