Here comes more logs! This was done with the suggest ARS short code change:
Turn Code: 0N; Number: 0N Feature: Dial
Into Code: 011N; Number: N Feature: Dial
The result was no different than before:
335747587mS CMExtnTx: v=193, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 4 Call: lid=0 id=-1 in=0
Called[AEkinaka Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [AEkinaka Msgs=0]
Timed: 11/01/10 16:41
335748678mS PRN: 193: Digit Pressed 7
335748683mS CMExtnRx: v=193, p1=0
CMSetup
Line: type=DigitalExtn 4 Call: lid=0 id=15782 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
335748685mS CMTARGET: 0.15782.0 3507 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=193 dir=out complete=0 ses=0
335748685mS CMTARGET: 0.15782.0 3507 AEkinaka.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
335748687mS CMExtnTx: v=193, p1=0
CMSetupAck
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
BChan: slot=10 chan=21
Timed: 11/01/10 16:41
335748688mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
335748688mS CMTARGET: 0.15782.0 3507 AEkinaka.0: Setting Hard Timer 4000
335748689mS CMTARGET: 0.15782.0 3507 AEkinaka.0: LOOKUP CALL ROUTE: type=100 called_party=7 sub= calling=193 dir=out complete=0 ses=0
335748689mS CMTARGET: 0.15782.0 3507 AEkinaka.0: ADD TARGET (N): number=7 type=100 depth=1 nobar=1 setorig=1 ses=0
335748690mS CMTARGET: 0.15782.0 3507 AEkinaka.0: SYS SC: 7 2 sc=type=Dial code=7N, num=N callinfop->sending_complete=0 secondary_dialtone=
335748690mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=50
335748691mS CMARS: CMARSHandler::MakeCallTarget - Called Number:
335748691mS CMTARGET: 0.15782.0 3507 AEkinaka.0: CancelTimer CMTCDelayedProcessing
335748691mS CMTARGET: 0.15782.0 3507 AEkinaka.0: INITIAL TARGETING SUCCEEDED
335748692mS CMTARGET: 0.15782.0 3507 AEkinaka.0: GetNoAnswerTimer:15
335748694mS CMTARGET: 0.15782.0 3507 AEkinaka.0: CancelTimer CMTCNoAnswerTimeout
335748695mS CMARS: FORM: 7 - Received Number:
335748695mS CMARS: CMARSTargetingPending
335748696mS CD: CALL: 0.15782.0 BState=Idle Cut=2 Music=3.0 Aend="AEkinaka(193)" (10.21) Bend="" [] (0.0) CalledNum= () CallingNum=193 (AEkinaka) Internal=1 Time=17 AState=Dialling
335748699mS CMMap: a=10.21 b=0.0 D1
335748703mS CD: CALLSYNC: cs02
335749641mS PRN: 193: Digit Pressed 0
335749642mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
335749643mS CMARS: FORM: 7 - Received Number: 0
335749643mS CMARS: CMARSTargetingPending
335749645mS CMMap: a=10.21 b=0.0 D0
335749933mS PRN: 193: Digit Pressed 1
335749934mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
335749935mS CMARS: FORM: 7 - Received Number: 01
335749935mS CMARS: CMARSTargetingPending
335750117mS PRN: 193: Digit Pressed 1
335750118mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
335750119mS CMARS: FORM: 7 - Received Number: 011
335750119mS CMARS: CMARSTargetingPending
335750480mS PRN: 193: Digit Pressed 3
335750481mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
335750481mS CMARS: FORM: 7 - Received Number: 0113
335750482mS CMARS: CMARSTargetingPending
335750687mS PRN: 193: Digit Pressed 9
335750688mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
335750689mS CMARS: FORM: 7 - Received Number: 01139
335750689mS CMARS: CMARSTargetingPending
335751277mS PRN: 193: Digit Pressed 0
335751278mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
335751278mS CMARS: FORM: 7 - Received Number: 011390
335751279mS CMARS: CMARSTargetingPending
335751514mS PRN: 193: Digit Pressed 4
335751515mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[4] Type=Default (100) Reason=CMDRdirect
335751516mS CMARS: FORM: 7 - Received Number: 0113904
335751516mS CMARS: CMARSTargetingPending
335751793mS PRN: 193: Digit Pressed 2
335751794mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
335751795mS CMARS: FORM: 7 - Received Number: 01139042
335751795mS CMARS: CMARSTargetingPending
335751978mS PRN: 193: Digit Pressed 2
335751979mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
335751979mS CMARS: FORM: 7 - Received Number: 011390422
335751980mS CMARS: CMARSTargetingPending
335752218mS PRN: 193: Digit Pressed 4
335752219mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[4] Type=Default (100) Reason=CMDRdirect
335752219mS CMARS: FORM: 7 - Received Number: 0113904224
335752220mS CMARS: CMARSTargetingPending
335752452mS PRN: 193: Digit Pressed 3
335752453mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
335752454mS CMARS: FORM: 7 - Received Number: 01139042243
335752454mS CMARS: CMARSTargetingPending
335752679mS PRN: 193: Digit Pressed 3
335752680mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
335752680mS CMARS: FORM: 7 - Received Number: 011390422433
335752681mS CMARS: CMARSTargetingPending
335753064mS PRN: 193: Digit Pressed 0
335753065mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
335753066mS CMARS: FORM: 7 - Received Number: 0113904224330
335753066mS CMARS: CMARSTargetingPending
335753305mS PRN: 193: Digit Pressed 1
335753306mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
335753307mS CMARS: FORM: 7 - Received Number: 01139042243301
335753308mS CMARS: CMARSTargetingPending
335753503mS PRN: 193: Digit Pressed 1
335753504mS CMExtnRx: v=193, p1=0
CMInformation
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
335753504mS CMARS: FORM: 7 - Received Number: 011390422433011
335753505mS CMARS: CMARSTargetingPending
335757691mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 011390422433011 Addr: fe92a050
335757691mS CMARS: FORM: 7 - Received Number: 011390422433011
335757692mS CMARS: FOUND A SHORT CODE - short_code: 011N; - Tel: N - Called_Party: 390422433011 - Line Group Id: 0
335757692mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=0
335757692mS CMLRQ: CMLRQHandler::FindActiveLRQByGroupID GroupID=0
335757709mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 390422433011 - Calling Number: 193
335757709mS CMARS: SEND Setup TO LINE
335757710mS PRN: Setting configured voice gain for ch 23.
335757710mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=15785 in=0
Called[390422433011] Type=National (2) Reason=CMDRdirect Calling[9492722401] Type=National Plan=ISDN Pres=AllowedIndSend (101)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=23
Locale: enu
335757711mS CD: CALL: 0.15782.0 BState=Idle Cut=2 Music=0.0 Aend="AEkinaka(193)" (10.21) Bend="" [Line 1] (0.0) CalledNum=011390422433011 () CallingNum=193 (AEkinaka) Internal=1 Time=9032 AState=Dialling
335757715mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=15785
335757716mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0067(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 80 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 0d a0 33 39 30 34 32 32 34 33 33 30 31 31 p..390422433011
335757717mS RES: Mon 11/1/2010 16:41:11 FreeMem=43250720(1) CMMsg=6 (6) Buff=100 579 497 1021 4 Links=5422
335757718mS RES2: RTEngine=0, CMRTEngine=0, Timer=64, Poll=0, Ready=0, CMReady=1, CMQueue=0, VPNNQueue=0
335757762mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0067(Local)
Message Type = ReleaseComplete
InformationElement = CAUSE
0000 08 02 c0 84 ....
335757763mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=15785
335757765mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=15785 in=0
BChan: slot=0 chan=23
Cause=4, Send special information tone/(NI-2)Vacant Code
335757765mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
335757770mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=15785 in=0
Cause=16, Normal call clearing
335757771mS CMARS: Target: Short_Code: 011N; - Line_Group_ID: 0 has been set to: CMARS_OUTOFSERVICE_ALL
335757772mS CD: CALL: 0.15782.0 BState=Idle Cut=2 Music=0.0 Aend="AEkinaka(193)" (10.21) Bend="" [] (0.0) CalledNum=011390422433011 () CallingNum=193 (AEkinaka) Internal=1 Time=9092 AState=Dialling
335757775mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=15785 in=0
Cause=16, Normal call clearing
335764418mS H323Evt: Recv: RegistrationRequest 640a0af1; Endpoints registered: 6; Endpoints in registration: 0
335769594mS H323Evt: Recv: RegistrationRequest ac190313; Endpoints registered: 6; Endpoints in registration: 0
335770593mS CMExtnRx: v=193, p1=0
CMReleaseComp
Line: type=DigitalExtn 4 Call: lid=0 id=15782 in=0
335770597mS CMLOGGING: CALL:2010/01/1116:41,00:00:00,000,193,O,7011390422433011,7011390422433011,AEkinaka,,,1,,""n/a,0
335770598mS CD: CALL: 0.15782.0 BState=Idle Cut=2 Music=0.0 Aend="AEkinaka(193)" (10.21) Bend="" [] (0.0) CalledNum=011390422433011 () CallingNum=193 (AEkinaka) Internal=1 Time=21918 AState=Idle
335770599mS CD: CALL: 0.15782.0 Deleted
335770601mS CMARS: CMARSEndpoint::CallLost(cause=16) - Address: 0.15784.0 3507 ARS for 7 - Call State: CMCSOverlapRecv
335770603mS CMTARGET: 0.15782.0 -1 BaseEP: ~CMTargetHandler
335770619mS CD: CALLSYNC: cs02
335770714mS RES: Mon 11/1/2010 16:41:24 FreeMem=43278628(1) CMMsg=6 (6) Buff=100 583 497 1021 4 Links=5430
335770714mS RES2: RTEngine=0, CMRTEngine=0, Timer=66, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
335771397mS H323Evt: Recv: RegistrationRequest ac190311; Endpoints registered: 6; Endpoints in registration: 0
335772004mS H323Evt: Recv: RegistrationRequest ac1a0165; Endpoints registered: 6; Endpoints in registration: 0
335772601mS CMExtnTx: v=193, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 4 Call: lid=0 id=-1 in=0
Called[AEkinaka Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [AEkinaka Msgs=0]
Timed: 11/01/10 16:41
It found the updated shortcode and did not send the 011 it looks like:
FOUND A SHORT CODE - short_code: 011N; - Tel: N - Called_Party: 390422433011 - Line Group Id: 0
However, it was squaking about a Vacant code:
CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=15785 in=0
BChan: slot=0 chan=23
Cause=4, Send special information tone/(NI-2)Vacant Code
It was worth a shot GoCarSki! And yes, we are in Southern California...
