Can't Dial out on PRI, Can receive calls

[kfisher33]

Anyone Ever Seen This ?

394958mS RES: Tue 22/9/2009 06:58:24 FreeMem=76972252(1) CMMsg=4 (4) Buff=200 964 999 7430 5 Links=20715
394958mS RES2: RTEngine=0, CMRTEngine=0, Timer=43, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
396275mS CMCallEvt: 0.1018.0 -1 BaseEP: NEW CMEndpoint f57ff34c TOTAL NOW=1 CALL_LIST=0
396275mS CMCallEvt: CREATE CALL:5 (f57feedc)
396275mS CMCallEvt: 0.1019.0 -1 BaseEP: NEW CMEndpoint f57fdef8 TOTAL NOW=2 CALL_LIST=0
396276mS CMExtnEvt: Extn572: CMExtnHandler::SetCurrent( id: 0->1018 )
396277mS CMExtnRx: v=572, p1=0
CMSetup
Line: type=DigitalExtn 7 Call: lid=0 id=1018 in=0
Called[917204445124] Type=Default (100) Reason=CMDRdirect Calling[572] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110) Extn572
IE CMIECallingPartyKName (226) Extn572
396277mS CMCallEvt: 0.1018.0 5 Extn572.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
396278mS CMExtnEvt: v=18 State, new=Connected old=Idle,0,0,Extn572
396278mS CMTARGET: 0.1018.0 5 Extn572.0: Setting Hard Timer 4000
396278mS CMTARGET: 0.1018.0 5 Extn572.0: LOOKUP CALL ROUTE: type=100 called_party=917204445124 sub= calling=572 dir=out complete=0 ses=0
396279mS CMTARGET: 0.1018.0 5 Extn572.0: ADD TARGET (N): number=917204445124 type=100 depth=1 nobar=1 setorig=1 ses=0
396279mS CMTARGET: 0.1018.0 5 Extn572.0: SYS SC: 917204445124 2 17204445124 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
396279mS CMARS: FindActiveARSByGroupID GroupID=51 - Found
396280mS CMARS: MakeCallTarget - Called Number: 17204445124
396280mS CMCallEvt: 0.1020.0 -1 BaseEP: NEW CMEndpoint f57f59a4 TOTAL NOW=3 CALL_LIST=1
396280mS CMTARGET: 0.1018.0 5 Extn572.0: CancelTimer CMTCDelayedProcessing
396280mS CMTARGET: 0.1018.0 5 Extn572.0: INITIAL TARGETING SUCCEEDED
396280mS CMTARGET: 0.1018.0 5 Extn572.0: GetNoAnswerTimer:15
396281mS CMCallEvt: 0.1018.0 5 Extn572.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
396281mS CMExtnEvt: v=18 State, new=Dialling old=Connected,0,0,Extn572
396281mS CMExtnTx: v=572, p1=0
CMSetupAck
Line: type=DigitalExtn 7 Call: lid=0 id=1018 in=0
BChan: slot=9 chan=20
Timed: 22/09/09 06:58
396281mS CMCallEvt: 0.1019.0 5 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
396282mS CMCallEvt: 0.1020.0 5 ARS for ARS1: StateChange: END=T CMCSIdle->CMCSOffering
396282mS CMCallEvt: 0.1019.0 5 TargetingEP: RequestEnd 0.1020.0 5 ARS for ARS1
396283mS CMTARGET: 0.1018.0 5 Extn572.0: CancelTimer CMTCNoAnswerTimeout
396283mS CMCallEvt: 0.1020.0 5 ARS for ARS1: StateChange: END=B CMCSOffering->CMCSOverlapRecv
396283mS CMARS: FORM: ARS1 - Received Number: 17204445124
396283mS CMARS: CMARSTargetingPending
396284mS CMCallEvt: 0.1019.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
396284mS CMCallEvt: 0.1019.0 -1 BaseEP: DELETE CMEndpoint f57fdef8 TOTAL NOW=2 CALL_LIST=1
396284mS CD: CALL: 0.1018.0 BState=Idle Cut=2 Music=0.0 Aend="Extn572(572)" (9.20) Bend="" [] (0.0) CalledNum=917204445124 () CallingNum=572 (Extn572) Internal=1 Time=9 AState=Dialling
400280mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 17204445124 Addr: f57f59a4
400280mS CMARS: FORM: ARS1 - Received Number: 17204445124
400280mS CMARS: FOUND A SHORT CODE - short_code: 1N; - Tel: 1Nsi2547423500 - Called_Party: 17204445124 - Line Group Id: 5
400281mS CMARS: FindActiveARSByGroupID GroupID=5 - Not Found
400281mS CMLRQ: FindActiveLRQByGroupID GroupID=5 - Not Found
400281mS CMCallEvt: 0.1021.0 -1 BaseEP: NEW CMEndpoint f57ebb7c TOTAL NOW=3 CALL_LIST=1
400287mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 5 - Called Number: 17204445124 - Calling Number: 2547423500
400287mS CMARS: SEND Setup TO LINE
400287mS CMCallEvt: 0.1021.0 5 Q931 Trunk:1 CHAN=23: StateChange: END=child CMCSIdle->CMCSOffering
400288mS PRN: Setting configured voice gain for ch 23.
400288mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=1021 in=0
Called[17204445124] Type=Unknown (0) Reason=CMDRdirect Calling[2547423500] 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
400288mS CD: CALL: 0.1018.0 BState=Idle Cut=2 Music=0.0 Aend="Extn572(572)" (9.20) Bend="" [Line 1] (0.0) CalledNum=917204445124 () CallingNum=572 (Extn572) Internal=1 Time=4013 AState=Dialling
400289mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=1021
400349mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=1021
400350mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=1021 in=0
BChan: slot=0 chan=23
Cause=100, Invalid information element contents
400350mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
400352mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=1021 in=0
Cause=16, Normal call clearing
400353mS CMCallEvt: 0.1021.0 -1 Q931 Trunk:1 CHAN=23: StateChange: END=X CMCSOffering->CMCSCompleted
400353mS CMARS: Target: Short_Code: 1N; - Line_Group_ID: 5 has been set to: CMARS_OUTOFSERVICE_ALL
400353mS CD: CALL: 0.1018.0 BState=Idle Cut=2 Music=0.0 Aend="Extn572(572)" (9.20) Bend="" [] (0.0) CalledNum=917204445124 () CallingNum=572 (Extn572) Internal=1 Time=4078 AState=Dialling
400354mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=1021 in=0
Cause=16, Normal call clearing
400355mS CMCallEvt: 0.1021.0 -1 Q931 Trunk:1 CHAN=23: StateChange: END=X CMCSCompleted->CMCSDelete
400355mS CMCallEvt: 0.1021.0 -1 BaseEP: DELETE CMEndpoint f57ebb7c TOTAL NOW=2 CALL_LIST=1
400458mS RES: Tue 22/9/2009 06:58:30 FreeMem=76950240(1) CMMsg=4 (4) Buff=200 964 999 7430 5 Links=20709
400458mS RES2: RTEngine=0, CMRTEngine=0, Timer=43, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
426239mS CMExtnRx: v=572, p1=0
CMReleaseComp
Line: type=DigitalExtn 7 Call: lid=0 id=1018 in=0
426239mS CMCallEvt: 0.1018.0 5 Extn572.0: StateChange: END=A CMCSDialling->CMCSCompleted
426240mS CMExtnEvt: v=18 State, new=PortRecoverDelay old=Dialling,0,0,Extn572
426241mS CMLOGGING: CALL:2009/09/2206:58,00:00:00,000,572,O,917204445124,917204445124,Extn572,,,1,,""n/a,0
426241mS CD: CALL: 0.1018.0 BState=Idle Cut=2 Music=0.0 Aend="Extn572(572)" (9.20) Bend="" [] (0.0) CalledNum=917204445124 () CallingNum=572 (Extn572) Internal=1 Time=29966 AState=Idle
426241mS CD: CALL: 0.1018.0 Deleted
426241mS CMExtnEvt: Extn572: CALL LOST (CMCauseNormal)
426242mS CMExtnEvt: Extn572: Extn(572) Calling Party Number(572) Type(CMNTypeInternal)
426242mS CMExtnEvt: Extn572: CMExtnHandler::SetCurrent( id: 1018->0 )
426242mS CMCallEvt: 0.1018.0 -1 Extn572.-1: StateChange: END=X CMCSCompleted->CMCSDelete
426243mS CMARS: CMARSEndpoint::CallLost(cause=16) - Address: 0.1020.0 5 ARS for ARS1 - Call State: CMCSOverlapRecv
426243mS CMCallEvt: 0.1020.0 -1 ARS for ARS1: StateChange: END=X CMCSOverlapRecv->CMCSDelete
426243mS CMCallEvt: 0.1020.0 -1 BaseEP: DELETE CMEndpoint f57f59a4 TOTAL NOW=1 CALL_LIST=0
426243mS CMCallEvt: END CALL:5 (f57feedc)
426244mS CMTARGET: 0.1018.0 -1 BaseEP: ~CMTargetHandler
426244mS CMCallEvt: 0.1018.0 -1 BaseEP: DELETE CMEndpoint f57ff34c TOTAL NOW=0 CALL_LIST=0
426458mS RES: Tue 22/9/2009 06:58:56 FreeMem=76972760(1) CMMsg=4 (4) Buff=200 964 999 7430 5 Links=20715
426458mS RES2: RTEngine=0, CMRTEngine=0, Timer=44, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
428242mS CMExtnEvt: Extn572: Recover Timer reason=CMTRWrapUp
428242mS CMExtnEvt: v=18 State, new=Idle old=PortRecoverDelay,0,0,Extn572
428242mS CMExtnTx: v=572, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 7 Call: lid=0 id=-1 in=0
Called[Extn572 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn572 Msgs=0]
Timed: 22/09/09 06:58
478104mS ISDNL1Evt: v=1 peb=1,F2 F1
478105mS ISDNL1Evt: v=1 peb=1,F1 F2
478111mS ISDNL1Evt: v=1 peb=1,F3 F1
478113mS ISDNL1Evt: v=1 peb=1,F5 F3
478114mS PRN: Slot 1, Falc 1: LOCK RAI crc=1 set=0 FMR1=de FMR2=60
478120mS ISDNL1Evt: v=1 peb=1,UnLocked
478120mS PRN: Lock To Internal Clock, FPGA_REC_CLK_SELECT = 0x00
478458mS RES: Tue 22/9/2009 06:59:48 FreeMem=76969864(1) CMMsg=4 (4) Buff=200 964 999 7430 5 Links=20716
478458mS RES2: RTEngine=0, CMRTEngine=0, Timer=42, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
478611mS ISDNL1Evt: v=1 peb=1,PHDI
480113mS PRN: Slot 1, Falc 1: YELLOW ALARM ON
483611mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=4,p4=127,s1=
483611mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
483612mS CMLineRx: v=1
CMLineDown
Line: type=Q931Line 1 Call: lid=0 id=-1 in=0
483612mS CMLineTx: v=1
CMRestart
Line: type=Q931Line 1 Call: lid=0 id=-1 in=0
483622mS CMTARGET: CMQ931Trunk:reprocessInboundMsg - failure_timer set
483962mS RES: Tue 22/9/2009 06:59:53 FreeMem=76966804(1) CMMsg=4 (4) Buff=200 964 999 7429 5 Links=20709
483962mS RES2: RTEngine=0, CMRTEngine=0, Timer=44, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
487623mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
521623mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
555623mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
589623mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
608958mS RES: Tue 22/9/2009 07:01:58 FreeMem=76964348(1) CMMsg=4 (4) Buff=200 964 999 7430 5 Links=20707
608958mS RES2: RTEngine=0, CMRTEngine=0, Timer=42, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
610722mS PRN: Config Write Wake Up
611223mS PRN: New IO List Size 1
611223mS PRN: Sending Config out to f57e8e18 started
611224mS PRN: Start WriteConfig at f7d4358c savemode 0 writetype 2
611439mS PRN: Sending Config out to f57e8e18 finished error=0
611439mS PRN: Config Write Completed
623623mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
657623mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
691623mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
725623mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
759623mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
793623mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=

 

[TomMills]

Target: Short_Code: 1N; - Line_Group_ID: 5 has been set to: CMARS_OUTOFSERVICE_ALL

might be relevant.

http://www.twitter.com/Millsano85

See what my brain does when it's not thinking about phones.

 

[amriddle01]

By default Incoing call routes are line gp 0, this is probably why incoming works, what is line gp 5 ?

ACS - IP Office Implement

"What the Crocodile Hat....was that?

 

[kfisher33]

I try changing my PRI channels to line group 5,, I changed from 0 to 5, does the same thing. I defaulted the switch yesterday and still couldn't dial out. I think it is something to do with the provider. But the circuit will work on the old nortel.

 

[kfisher33]

Yes,
I am thinking this is relevent myself and also, I am getting a - cause 100
Target: Short_Code: 1N; - Line_Group_ID: 5 has been set to: CMARS_OUTOFSERVICE_ALL

 

[amriddle01]

Are the Incoming call routes on line gp 5 or 0 ? Do you have two sets of lines?

ACS - IP Office Implement

"What the Crocodile Hat....was that?

 

[kfisher33]

I changed the line group and channels back to 0

 

[amriddle01]

Have you also chnaged the shortcodes to reflect this change? So they now dial out on line Gp 0 also

ACS - IP Office Implement

"What the Crocodile Hat....was that?

 

[kfisher33]

yes, the ARS is set to 0

 

[amriddle01]

What does SSA show for the line/trunk state, how does it see the Incoming calls/outgiong calls progress?

ACS - IP Office Implement

"What the Crocodile Hat....was that?

 

[kfisher33]

On the incoming calls, it shows connected.
On the out bound, I can see when I dial the timer on channel 23 reset from an idle state and start counting, but the channel continues to show idle.

 

[amriddle01]

How is this circuit being presented? It sounds like it isn't a real NTE more like something trying to emulate a PRI line but isn't quite set right.

ACS - IP Office Implement

"What the Crocodile Hat....was that?

 

[kfisher33]

it is coming from a smart jack

 

[PRNDL2]

I once had the same condition on a Pri where the last few channels were part of another trunk gp and were not pri channels...they were set to T1 w/ wink/wink sig. The T1 vendor fought me on this but eventually fixed the problem.

 

[kfisher33]

It is fixed, I finally got ahold of the service provider, once they figured who owned the circuit and found there was only 16 channels instead of 23.

All is good.

Thanks for all your input!!