IPO 500 PRI Setup for complete amatuer 5

[Mortigan]

Hi guys. I'm a new poster on these forums but have been reading all of your tips for several weeks now. I've been setting up an IP Office 500 system and have been doing pretty well so far. I just had our PRI line installed (16 channel) and I'm trying to configure the system to use it. I really just wanted to see if anyone has any resources on a basic overview of how this setup will work. From what I've been reading it should be relatively straight forward however I am a systems/networking guy, not a telecom guy at all so my knowledge of such is limited. Any assistance would be GREATLY appreciated.

Thanks,
Mortigan

 

[InNeedOff]

easy Question, does the green light on the front of the PRI card come on and stay on?

 

[Mortigan]

Sorry for the delay in my response, I have been out of the office for a couple days. @ Tlpeter, here is the trace you requested:
********** Warning: Logging to Screen Stopped **********
488705397mS CMCallEvt: 0.1019.0 -1 BaseEP: NEW CMEndpoint f57d8784 TOTAL NOW=1 CALL_LIST=0
488705398mS PRN: Created MH f57da6e4 parent 251.1019.0 -1 jfegley.-1
488705398mS CMCallEvt: CREATE CALL:6 (f565ec70)
488705398mS CMCallEvt: 0.1020.0 -1 BaseEP: NEW CMEndpoint f565dc94 TOTAL NOW=2 CALL_LIST=0
488705399mS CMExtnEvt: jfegley: CMExtnHandler::SetCurrent( id: 0->1019 )
488705401mS CMExtnRx: v=2001, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[2001] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110) jfegley
IE CMIECallingPartyKName (226) Joseph Fegley
IE CMIEFastStartInfoData (6)
488705402mS CMCallEvt: 251.1019.0 6 jfegley.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
488705402mS CMExtnEvt: v=7 State, new=Connected old=Idle,0,0,jfegley
488705403mS CMTARGET: 251.1019.0 6 jfegley.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=2001 dir=out complete=0 ses=0
488705403mS CMTARGET: 251.1019.0 6 jfegley.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
488705403mS CMCallEvt: 251.1019.0 6 jfegley.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
488705404mS CMExtnEvt: v=7 State, new=Dialling old=Connected,0,0,jfegley
488705406mS CMExtnTx: v=2001, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
IE CMIEFastStartInfoData (6)
Timed: 28/05/09 10:20
488705406mS CMExtnTx: v=2001, p1=8000
CMFacility
Line: type=IPLine 250 Call: lid=251 id=7 in=1
IE CMIEFastStartInfoData (6)
488705406mS CMExtnTx: v=2001, p1=0
CMSetupAck
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Timed: 28/05/09 10:20
488705407mS CD: CALL: 251.1019.0 BState=Idle Cut=1 Music=3.0 Aend="jfegley(2001)" (0.0) Bend="" [] (0.0) CalledNum= () CallingNum=2001 (jfegley) Internal=1 Time=9 AState=DialInitiated
488705408mS CMMap: PCG::AddToneGenerator g D1[1] append pcp[168]b0r1 (total 1)
488705408mS CMMap: a=0.0 b=0.0 pcp[167]b0r1 RTPD1
488705761mS RES: Thu 28/5/2009 10:20:39 FreeMem=76132560(3) CMMsg=5 (6) Buff=200 957 1000 7414 5 Links=22140
488705761mS RES2: RTEngine=0, CMRTEngine=0, Timer=56, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
488706403mS H323Evt: RTP(50t): 192.168.2.230/49152 192.168.2.72/52704 CODEC 6 PKTSZ=20 RFC2833=off AGE=995 SENT 50 (avg size=20) RECV 18 (avg size=20)
488711051mS PRN: 2001: Digit Pressed 9
488711051mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
488711052mS CMTARGET: 251.1019.0 6 jfegley.0: Setting Hard Timer 4000
488711052mS CMTARGET: 251.1019.0 6 jfegley.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=2001 dir=out complete=0 ses=0
488711052mS CMTARGET: 251.1019.0 6 jfegley.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
488711052mS CMTARGET: 251.1019.0 6 jfegley.0: SYS SC: 9 2 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
488711053mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=50
488711053mS CMARS: CMARSHandler::MakeCallTarget - Called Number:
488711053mS CMCallEvt: 0.1021.0 -1 BaseEP: NEW CMEndpoint f56535d4 TOTAL NOW=3 CALL_LIST=1
488711053mS CMTARGET: 251.1019.0 6 jfegley.0: CancelTimer CMTCDelayedProcessing
488711054mS CMTARGET: 251.1019.0 6 jfegley.0: INITIAL TARGETING SUCCEEDED
488711054mS CMTARGET: 251.1019.0 6 jfegley.0: GetNoAnswerTimer:15
488711054mS CMCallEvt: 0.1020.0 6 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
488711055mS CMCallEvt: 0.1021.0 6 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
488711056mS CMCallEvt: 0.1020.0 6 TargetingEP: RequestEnd 0.1021.0 6 ARS for Main
488711056mS CMTARGET: 251.1019.0 6 jfegley.0: CancelTimer CMTCNoAnswerTimeout
488711056mS CMCallEvt: 0.1021.0 6 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
488711057mS CMARS: FORM: Main - Received Number:
488711057mS CMARS: CMARSTargetingPending
488711057mS CMCallEvt: 0.1020.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
488711057mS CMCallEvt: 0.1020.0 -1 BaseEP: DELETE CMEndpoint f565dc94 TOTAL NOW=2 CALL_LIST=1
488711058mS CD: CALL: 251.1019.0 BState=Idle Cut=2 Music=3.0 Aend="jfegley(2001)" (0.0) Bend="" [] (0.0) CalledNum= () CallingNum=2001 (jfegley) Internal=1 Time=5659 AState=Dialling
488711058mS CMMap: a=0.0 b=0.0 pcp[167]b0r1 RTPD1
488711261mS RES: Thu 28/5/2009 10:20:44 FreeMem=76128896(3) CMMsg=5 (6) Buff=200 958 1000 7414 5 Links=22136
488711261mS RES2: RTEngine=0, CMRTEngine=0, Timer=54, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
488712212mS PRN: 2001: Digit Pressed 1
488712212mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
488712213mS CMARS: FORM: Main - Received Number: 1
488712213mS CMARS: CMARSTargetingPending
488712214mS CMMap: a=0.0 b=0.0 pcp[167]b0r1 RTPD0
488713287mS PRN: 2001: Digit Pressed 4
488713287mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
488713288mS CMARS: FORM: Main - Received Number: 14
488713288mS CMARS: CMARSTargetingPending
488713742mS PRN: 2001: Digit Pressed 0
488713743mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
488713743mS CMARS: FORM: Main - Received Number: 140
488713743mS CMARS: CMARSTargetingPending
488714065mS PRN: 2001: Digit Pressed 7
488714066mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
488714066mS CMARS: FORM: Main - Received Number: 1407
488714066mS CMARS: CMARSTargetingPending
488714654mS PRN: 2001: Digit Pressed 5
488714654mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[5] Type=Default (100) Reason=CMDRdirect Keypad[5]
488714654mS CMARS: FORM: Main - Received Number: 14075
488714655mS CMARS: CMARSTargetingPending
488714816mS PRN: ConferDSP is alive
488715049mS PRN: 2001: Digit Pressed 1
488715049mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
488715050mS CMARS: FORM: Main - Received Number: 140751
488715050mS CMARS: CMARSTargetingPending
488715572mS PRN: 2001: Digit Pressed 7
488715573mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
488715573mS CMARS: FORM: Main - Received Number: 1407517
488715573mS CMARS: CMARSTargetingPending
488715987mS PRN: 2001: Digit Pressed 4
488715988mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
488715988mS CMARS: FORM: Main - Received Number: 14075174
488715988mS CMARS: CMARSTargetingPending
488716268mS PRN: 2001: Digit Pressed 7
488716268mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
488716269mS CMARS: FORM: Main - Received Number: 140751747
488716269mS CMARS: CMARSTargetingPending
488716604mS PRN: 2001: Digit Pressed 2
488716605mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[2] Type=Default (100) Reason=CMDRdirect Keypad[2]
488716605mS CMARS: FORM: Main - Received Number: 1407517472
488716605mS CMARS: CMARSTargetingPending
488716909mS PRN: 2001: Digit Pressed 4
488716909mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
488716910mS CMARS: FORM: Main - Received Number: 14075174724
488716910mS CMARS: CMARSTargetingPending
488718930mS H323Evt: Recv: RegistrationRequest c0a802a9; Endpoints registered: 3; Endpoints in registration: 0
488721053mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 14075174724 Addr: f56535d4
488721053mS CMARS: FORM: Main - Received Number: 14075174724
488721053mS CMARS: FOUND A SHORT CODE - short_code: 1N; - Tel: 1N - Called_Party: 14075174724 - Line Group Id: 0
488721054mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=0
488721054mS CMLRQ: CMLRQHandler::FindActiveLRQByGroupID GroupID=0
488721054mS CMLineTx: v=1
CMRestart
Line: type=Q931Line 1 Call: lid=0 id=-1 in=0
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
Display [enu]
Locale: enu
488721054mS CMTARGET: Problem with Line Id: 1 - preselected: 00000000, Unlicenced: 0, OperationalTest: 1, NewCallResourceCheck: -1
488721055mS CMMap: a=0.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 20
488721055mS CMCallEvt: 0.1022.0 -1 BaseEP: NEW CMEndpoint f5651c74 TOTAL NOW=3 CALL_LIST=1
488721056mS CMARS: FOUND LINE - Line Id: 6 - using line group id: 0 - Called Number: 14075174724 - Calling Number: 2001
488721056mS CMARS: SEND Setup TO LINE
488721057mS CMCallEvt: 0.1022.0 6 Alog Trunk:6: StateChange: END=child CMCSIdle->CMCSOffering
488721057mS CMLineTx: v=6
CMSetup
Line: type=AnalogueLine 6 Call: lid=0 id=1022 in=0
Called[14075174724] Type=Default (100) Reason=CMDRdirect SndComp Calling[2001] Type=Internal Plan=Default
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110) jfegley
IE CMIECallingPartyKName (226) Joseph Fegley
IE CMIERespondingPartyName (228) jfegley
IE CMIERespondingPartyKName (229) Joseph Fegley
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=2001
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceH323Phone type=33 number=8000 channel=0 rx_gain=32 tx_gain=32 ep_callid=1019 ipaddr=192.168.2.230 apps=0
IE CMIEMohSourceId (247) MOH Source = 255
Locale: enu
488721058mS CD: CALL: 251.1019.0 BState=Idle Cut=2 Music=0.0 Aend="jfegley(2001)" (0.0) Bend="" [Line 6] (0.0) CalledNum=14075174724 () CallingNum=2001 (jfegley) Internal=1 Time=15659 AState=Dialling
488721261mS RES: Thu 28/5/2009 10:20:54 FreeMem=76123184(3) CMMsg=5 (6) Buff=200 959 1000 7413 5 Links=22106
488721261mS RES2: RTEngine=0, CMRTEngine=0, Timer=56, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
488723446mS H323Evt: Recv: RegistrationRequest c0a80248; Endpoints registered: 3; Endpoints in registration: 0
488725057mS CD: CALL: 251.1019.0 BState=Idle Cut=2 Music=0.0 Aend="jfegley(2001)" (0.0) Bend="" [Line 6] (0.0) CalledNum=14075174724 () CallingNum=2001 (jfegley) Internal=1 Time=19659 AState=Dialling
488726058mS PRN: Windowed EchoCancellation stopped: channel=1, command= 0x501
488726059mS CMLineRx: v=6
CMReleaseComp
Line: type=AnalogueLine 6 Call: lid=0 id=1022 in=0
BChan: slot=3 chan=2
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=914075174724
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceAlogTrunk type=0 number=6 channel=0 rx_gain=32 tx_gain=32 ep_callid=1022 ipaddr=192.168.2.230 apps=0
488726059mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
488726060mS CMCallEvt: 0.1022.0 -1 Alog Trunk:6: StateChange: END=X CMCSOffering->CMCSDelete
488726061mS CMARS: Target: Short_Code: 1N; - Line_Group_ID: 0 has been set to: CMARS_OUTOFSERVICE
488726061mS CMCallEvt: 0.1021.0 6 ARS for Main: StateChange: END=B CMCSOverlapRecv->CMCSAccept
488726061mS CMCallEvt: 251.1019.0 6 jfegley.0: StateChange: END=A CMCSDialling->CMCSRingBack
488726061mS CMExtnEvt: v=7 State, new=Alerting old=Dialling,0,0,jfegley
488726062mS CMExtnTx: v=2001, p1=0
CMProgress
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [WAITING_FOR_LINE]
Timed: 28/05/09 10:20
488726062mS CMCallEvt: 0.1022.0 -1 BaseEP: DELETE CMEndpoint f5651c74 TOTAL NOW=2 CALL_LIST=1
488726062mS CD: CALL: 251.1019.0 BState=Ringing Cut=3 Music=0.0 Aend="jfegley(2001)" (0.0) Bend="" [] (0.0) CalledNum=14075174724 () CallingNum=2001 (jfegley) Internal=1 Time=20664 AState=Ringing
488726063mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
488726063mS CMMap: a=21.39 b=1.255 T
488726064mS CMMap: PCG::AddToneGenerator g T[20] append pcp[169]b0r1 (total 1)
488726064mS CMMap: a=0.17 b=0.0 IP::ReserveCodec pcp[169]b0r1 allocated CMRTVocoder resource busy 2, total 20, codec type 6
488726064mS CMMap: a=21.39 b=0.17 M2
488726064mS CMMap: PCG::MapBChan pcp[167]b0r1 cp_b 0 other_cp_b 0 type CGTypeDTMF
488726065mS CMMap: DTMF:layTones g T[20] ,EEEEE
488726065mS CMMap: Started playing tones, cp[167]b0r1 (from g null)
488726065mS CMMap: cp[167]b0r1 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
488726261mS RES: Thu 28/5/2009 10:20:59 FreeMem=76125284(3) CMMsg=5 (6) Buff=200 956 1000 7414 5 Links=22098
488726261mS RES2: RTEngine=0, CMRTEngine=0, Timer=56, Poll=0, Ready=1, CMReady=0, CMQueue=0, VPNNQueue=0
488727061mS CMARS: Retarget existing BUSY targets - Num of Busy Targets: 1 - Current Form: Main
488727886mS CMMap: PCG::UnmapBChan pcp[167]b0r1 cp_b 0 other_cp_b 0
488727886mS CMMap: a=21.39 b=0.0 T0
488727886mS CMMap: a=21.39 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
488727887mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
488727887mS CMMap: a=21.39 b=1.255 T
488727887mS CMMap: PCG::AddToneGenerator g T[21] append pcp[170]b0r1 (total 1)
488727887mS CMMap: a=0.33 b=0.0 IP::ReserveCodec pcp[170]b0r1 allocated CMRTVocoder resource busy 3, total 20, codec type 6
488727887mS CMMap: a=21.39 b=0.33 M2
488727888mS CMMap: PCG::MapBChan pcp[167]b0r1 cp_b 0 other_cp_b 0 type CGTypeDTMF
488727888mS CMMap: DTMF:layTones g T[21] ,EEEEE
488727888mS CMMap: Started playing tones, cp[167]b0r1 (from g null)
488727888mS CMMap: cp[167]b0r1 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
488727889mS CMMap: a=0.17 b=0.0 IP::FreeCodec pcp[169]b0r1 freed CMRTVocoder resource busy 3, total 20
488729439mS PRN: ConferDSP is alive
488729709mS CMMap: PCG::UnmapBChan pcp[167]b0r1 cp_b 0 other_cp_b 0
488729709mS CMMap: a=21.39 b=0.0 T0
488729709mS CMMap: a=21.39 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
488729710mS CMMap: a=0.33 b=0.0 IP::FreeCodec pcp[170]b0r1 freed CMRTVocoder resource busy 2, total 20
488732642mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
488733061mS CMARS: Retarget existing BUSY targets - Num of Busy Targets: 1 - Current Form: Main
488733061mS CMARS: Target: Short_Code: 1N; - Line_Group_ID: 0 - Telephone_Number: 1N - Line_Status: CMARS_OUTOFSERVICE
488733061mS CMLineTx: v=1
CMRestart
Line: type=Q931Line 1 Call: lid=0 id=-1 in=0
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
Display [enu]
Locale: enu
488733062mS CMTARGET: Problem with Line Id: 1 - preselected: 00000000, Unlicenced: 0, OperationalTest: 1, NewCallResourceCheck: -1
488733062mS CMCallEvt: 0.1023.0 -1 BaseEP: NEW CMEndpoint f5651c74 TOTAL NOW=3 CALL_LIST=1
488733063mS CMARS: FOUND LINE - Line Id: 7 - using line group id: 0 - Short Code: 1N; - Called Number: 14075174724 - Calling Number: 2001
488733063mS CMARS: Retarget result=SEND Setup TO LINE
488733064mS CMCallEvt: 0.1023.0 6 Alog Trunk:7: StateChange: END=child CMCSIdle->CMCSOffering
488733064mS CMLineTx: v=7
CMSetup
Line: type=AnalogueLine 7 Call: lid=0 id=1023 in=0
Called[14075174724] Type=Default (100) Reason=CMDRdirect SndComp Calling[2001] Type=Internal Plan=Default
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110) jfegley
IE CMIECallingPartyKName (226) Joseph Fegley
IE CMIERespondingPartyName (228) jfegley
IE CMIERespondingPartyKName (229) Joseph Fegley
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=2001
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceH323Phone type=33 number=8000 channel=0 rx_gain=32 tx_gain=32 ep_callid=1019 ipaddr=192.168.2.230 apps=0
IE CMIEMohSourceId (247) MOH Source = 255
Locale: enu
488733065mS CD: CALL: 251.1019.0 BState=Ringing Cut=3 Music=0.0 Aend="jfegley(2001)" (0.0) Bend="" [Line 7] (0.0) CalledNum=14075174724 () CallingNum=2001 (jfegley) Internal=1 Time=27666 AState=Ringing
488733065mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
488733066mS CMMap: a=21.39 b=1.255 T
488733066mS CMMap: PCG::AddToneGenerator g T[22] append pcp[171]b0r1 (total 1)
488733066mS CMMap: a=0.17 b=0.0 IP::ReserveCodec pcp[171]b0r1 allocated CMRTVocoder resource busy 2, total 20, codec type 6
488733066mS CMMap: a=21.39 b=0.17 M2
488733066mS CMMap: PCG::MapBChan pcp[167]b0r1 cp_b 0 other_cp_b 0 type CGTypeDTMF
488733067mS CMMap: DTMF:layTones g T[22] ,EEEEE
488733067mS CMMap: Started playing tones, cp[167]b0r1 (from g null)
488733067mS CMMap: cp[167]b0r1 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
488733261mS RES: Thu 28/5/2009 10:21:06 FreeMem=76119264(3) CMMsg=5 (6) Buff=200 959 1000 7413 5 Links=22065
488733261mS RES2: RTEngine=0, CMRTEngine=0, Timer=56, Poll=0, Ready=1, CMReady=0, CMQueue=0, VPNNQueue=0
488734888mS CMMap: PCG::UnmapBChan pcp[167]b0r1 cp_b 0 other_cp_b 0
488734888mS CMMap: a=21.39 b=0.0 T0
488734888mS CMMap: a=21.39 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
488734889mS CMMap: a=0.17 b=0.0 IP::FreeCodec pcp[171]b0r1 freed CMRTVocoder resource busy 2, total 20
488737064mS CD: CALL: 251.1019.0 BState=Ringing Cut=3 Music=0.0 Aend="jfegley(2001)" (0.0) Bend="" [Line 7] (0.0) CalledNum=14075174724 () CallingNum=2001 (jfegley) Internal=1 Time=31666 AState=Ringing
488738072mS PRN: Windowed EchoCancellation stopped: channel=2, command= 0x502
488738073mS CMLineRx: v=7
CMReleaseComp
Line: type=AnalogueLine 7 Call: lid=0 id=1023 in=0
BChan: slot=3 chan=3
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=914075174724
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceAlogTrunk type=0 number=7 channel=0 rx_gain=32 tx_gain=32 ep_callid=1023 ipaddr=192.168.2.230 apps=0
488738073mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSAccept
488738074mS CMCallEvt: 0.1023.0 -1 Alog Trunk:7: StateChange: END=X CMCSOffering->CMCSDelete
488738075mS CMARS: Target: Short_Code: 1N; - Line_Group_ID: 0 has been set to: CMARS_OUTOFSERVICE
488738075mS CMCallEvt: 0.1023.0 -1 BaseEP: DELETE CMEndpoint f5651c74 TOTAL NOW=2 CALL_LIST=1
488738075mS CD: CALL: 251.1019.0 BState=Ringing Cut=3 Music=0.0 Aend="jfegley(2001)" (0.0) Bend="" [] (0.0) CalledNum=14075174724 () CallingNum=2001 (jfegley) Internal=1 Time=32677 AState=Ringing
488738261mS RES: Thu 28/5/2009 10:21:11 FreeMem=76124868(3) CMMsg=5 (6) Buff=200 960 1000 7414 5 Links=22077
488738261mS RES2: RTEngine=0, CMRTEngine=0, Timer=55, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
488739075mS CMARS: Retarget existing BUSY targets - Num of Busy Targets: 1 - Current Form: Main
488739075mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
488739075mS CMMap: a=21.39 b=1.255 T
488739076mS CMMap: PCG::AddToneGenerator g T[23] append pcp[172]b0r1 (total 1)
488739076mS CMMap: a=0.17 b=0.0 IP::ReserveCodec pcp[172]b0r1 allocated CMRTVocoder resource busy 2, total 20, codec type 6
488739076mS CMMap: a=21.39 b=0.17 M2
488739076mS CMMap: PCG::MapBChan pcp[167]b0r1 cp_b 0 other_cp_b 0 type CGTypeDTMF
488739077mS CMMap: DTMF:layTones g T[23] ,EEEEE
488739077mS CMMap: Started playing tones, cp[167]b0r1 (from g null)
488739077mS CMMap: cp[167]b0r1 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
488740898mS CMMap: PCG::UnmapBChan pcp[167]b0r1 cp_b 0 other_cp_b 0
488740898mS CMMap: a=21.39 b=0.0 T0
488740898mS CMMap: a=21.39 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
488740899mS CMMap: a=0.17 b=0.0 IP::FreeCodec pcp[172]b0r1 freed CMRTVocoder resource busy 2, total 20
488742178mS H323Evt: Recv: RegistrationRequest c0a80112; Endpoints registered: 3; Endpoints in registration: 0
488743128mS CMExtnTx: v=2001, p1=8000
CMFacility
Line: type=IPLine 250 Call: lid=251 id=7 in=1
IE CMIEFastStartInfoData (6)
488743128mS CMExtnRx: v=2001, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
488743128mS CMCallEvt: 251.1019.0 6 jfegley.0: StateChange: END=A CMCSRingBack->CMCSCompleted
488743129mS CMExtnEvt: v=7 State, new=CMESCompleted old=Alerting,0,0,jfegley
488743130mS CMExtnTx: v=2001, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1019 in=0
IE CMIEFastStartInfoData (6)
Timed: 28/05/09 10:21
488743130mS CMLOGGING: CALL:2009/05/2810:20,00:00:17,000,2001,O,914075174724,914075174724,jfegley,,,1,,""n/a,0
488743131mS CD: CALL: 251.1019.0 BState=Ringing Cut=2 Music=0.0 Aend="jfegley(2001)" (0.0) Bend="" [] (0.0) CalledNum=14075174724 () CallingNum=2001 (jfegley) Internal=1 Time=37733 AState=Idle
488743131mS CD: CALL: 251.1019.0 Deleted
488743131mS CMExtnEvt: jfegley: CALL LOST (CMCauseNormal)
488743131mS CMExtnEvt: jfegley: Extn(2001) Calling Party Number(2001) Type(CMNTypeInternal)
488743131mS CMExtnEvt: jfegley: CMExtnHandler::SetCurrent( id: 1019->0 )
488743132mS CMCallEvt: 251.1019.0 -1 jfegley.-1: StateChange: END=X CMCSCompleted->CMCSDelete
488743132mS CMExtnEvt: v=7 State, new=PortRecoverDelay old=CMESCompleted,0,0,jfegley
488743133mS CMARS: CMARSEndpoint::CallLost(cause=16) - Address: 0.1021.0 6 ARS for Main - Call State: CMCSAccept
488743133mS CMCallEvt: 0.1021.0 -1 ARS for Main: StateChange: END=X CMCSAccept->CMCSDelete
488743133mS CMCallEvt: 0.1021.0 -1 BaseEP: DELETE CMEndpoint f56535d4 TOTAL NOW=1 CALL_LIST=0
488743133mS CMCallEvt: END CALL:6 (f565ec70)
488743134mS PRN: Destroyed MH f57da6e4 parent unknown
488743134mS CMTARGET: 251.1019.0 -1 BaseEP: ~CMTargetHandler
488743135mS CMCallEvt: 251.1019.0 -1 BaseEP: DELETE CMEndpoint f57d8784 TOTAL NOW=0 CALL_LIST=0
488743136mS H323Evt: RTP(END): 192.168.2.230/49152 192.168.2.72/52704 CODEC 6 PKTSZ=20 RFC2833=off AGE=37727 SENT 678 RECV 106 RTdelay=0 jitter=0 loss=256 remotejitter=0 remoteloss=0
488743136mS CMMap: a=0.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 0, total 20
488743261mS RES: Thu 28/5/2009 10:21:16 FreeMem=76161696(2) CMMsg=5 (6) Buff=200 958 1000 7414 5 Links=22151
488743261mS RES2: RTEngine=0, CMRTEngine=0, Timer=57, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
488744151mS PRN: ConferDSP is alive
488745068mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
488745132mS CMExtnEvt: jfegley: Recover Timer reason=CMTRWrapUp
488745132mS CMExtnEvt: v=7 State, new=Idle old=PortRecoverDelay,0,0,jfegley
488745133mS CMExtnTx: v=2001, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[jfegley Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [jfegley Msgs=0]
Timed: 28/05/09 10:21
488758608mS PRN: ConferDSP is alive
488773033mS PRN: ConferDSP is alive
488773932mS H323Evt: Recv: RegistrationRequest c0a802a9; Endpoints registered: 3; Endpoints in registration: 0
488778449mS H323Evt: Recv: RegistrationRequest c0a80248; Endpoints registered: 3; Endpoints in registration: 0
488787068mS ISDNL3Evt: v=1 p1=1,p2=1001,p3=5,p4=0,s1=
488787472mS PRN: ConferDSP is alive
488797211mS H323Evt: Recv: RegistrationRequest c0a80112; Endpoints registered: 3; Endpoints in registration: 0
488801929mS PRN: ConferDSP is alive
488816414mS PRN: ConferDSP is alive

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

@ INeedOff: The LEDs are as follows-
LED 1 blinks green every second and amber every 5 seconds
LED 9 stays lit green and blinks amber every 5 seconds

Please let me know if this helps at all and if I can provide any more information.

Thanks.

 

[tlpeter]

I see : Display [WAITING_FOR_LINE] and CMARS: Target: short_Code: 1N; - Line_Group_ID: 0 has been set to: CMARS_OUTOFSERVICE

Your trunk is not working

ACS - Implement IP Office
ACA - Implement IP Telephony -- ACA - Design IP Telephony
ACA - Voice Services Management
______________
Women and cats can do as they please and men and dogs should relax and get used to the idea!

 

[amriddle01]

You could have seen that from System Status, open that and look at you trunks as long as they are out of service and not displaying "Idle" calls will not work

ACS - IP Office Implement

"I'm just off to Hartlepool to buy some exploding trousers

 

[Mortigan]

OK if the trunk is not working are there any steps I can/should take to try to track down the problem? I have confirmed in the System Status that the PRI trunk is currently "Out of Service" and there has been no change on the functioning of the system. PLease help!

 

[Mortigan]

Guys, Is it possible that this could be a cable problem? I was told that I had to use a T1 crossover cable and since I don't have 1 available and con't find one to buy quickly I just made one. This got me link lights on my Adtran and on the IPO but I'm wondering if there's still the possibility that the cable is wrong. Anyone with any insight into this?

 

[Mortigan]

UPDATE: 5-28, 2:00pm EST:

So I've been messing with the PRI settings and now in the SSA I show the 8 PRI channels I've administratively set to "In service" showing a status of Idle. Now when I try to make calls I get a message of "Call Rejected". I feel like I'm moving in the right direction but I'm still not sure. I'm going to post a new trace while making a call with the new configuration:

747249mS CMCallEvt: 0.1017.0 -1 BaseEP: NEW CMEndpoint f5717f80 TOTAL NOW=1 CALL_LIST=0
747249mS PRN: Created MH f5717e74 parent 251.1017.0 -1 jfegley.-1
747249mS CMCallEvt: CREATE CALL:5 (f5717568)
747249mS CMCallEvt: 0.1018.0 -1 BaseEP: NEW CMEndpoint f571658c TOTAL NOW=2 CALL_LIST=0
747251mS CMExtnEvt: jfegley: CMExtnHandler::SetCurrent( id: 0->1017 )
747253mS CMExtnRx: v=2001, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[2001] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110) jfegley
IE CMIECallingPartyKName (226) Joseph Fegley
IE CMIEFastStartInfoData (6)
747253mS CMCallEvt: 251.1017.0 5 jfegley.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
747253mS CMExtnEvt: v=1 State, new=Connected old=Idle,0,0,jfegley
747254mS CMTARGET: 251.1017.0 5 jfegley.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=2001 dir=out complete=0 ses=0
747254mS CMTARGET: 251.1017.0 5 jfegley.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
747255mS CMCallEvt: 251.1017.0 5 jfegley.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
747255mS CMExtnEvt: v=1 State, new=Dialling old=Connected,0,0,jfegley
747257mS CMExtnTx: v=2001, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
IE CMIEFastStartInfoData (6)
Timed: 28/05/09 14:02
747257mS CMExtnTx: v=2001, p1=8000
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1 in=1
IE CMIEFastStartInfoData (6)
747258mS CMExtnTx: v=2001, p1=0
CMSetupAck
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Timed: 28/05/09 14:02
747258mS CD: CALL: 251.1017.0 BState=Idle Cut=1 Music=3.0 Aend="jfegley(2001)" (0.0) Bend="" [] (0.0) CalledNum= () CallingNum=2001 (jfegley) Internal=1 Time=9 AState=DialInitiated
747260mS CMMap: PCG::AddToneGenerator g D1[1] append pcp[163]b0r1 (total 1)
747260mS CMMap: a=0.0 b=0.0 pcp[162]b0r1 RTPD1
747260mS H323Evt: Recv: RegistrationRequest c0a80112; Endpoints registered: 3; Endpoints in registration: 0
747263mS RES: Thu 28/5/2009 14:02:08 FreeMem=76132060(2) CMMsg=5 (6) Buff=200 958 999 7412 5 Links=22097
747264mS RES2: RTEngine=1, CMRTEngine=0, Timer=58, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
748253mS H323Evt: RTP(50t): 192.168.2.230/49152 192.168.2.72/52704 CODEC 6 PKTSZ=20 RFC2833=off AGE=994 SENT 50 (avg size=20) RECV 29 (avg size=20)
749566mS PRN: Created WAV file MOH Source 1
749570mS PRN: Loading HoldMusic.wav from ipaddr=192.168.2.131
751728mS PRN: 2001: Digit Pressed 9
751729mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
751729mS CMTARGET: 251.1017.0 5 jfegley.0: Setting Hard Timer 4000
751729mS CMTARGET: 251.1017.0 5 jfegley.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=2001 dir=out complete=0 ses=0
751729mS CMTARGET: 251.1017.0 5 jfegley.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
751730mS CMTARGET: 251.1017.0 5 jfegley.0: SYS SC: 9 2 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
751730mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=50
751730mS CMARS: CMARSHandler::MakeCallTarget - Called Number:
751730mS CMCallEvt: 0.1019.0 -1 BaseEP: NEW CMEndpoint f570b0dc TOTAL NOW=3 CALL_LIST=1
751731mS CMTARGET: 251.1017.0 5 jfegley.0: CancelTimer CMTCDelayedProcessing
751731mS CMTARGET: 251.1017.0 5 jfegley.0: INITIAL TARGETING SUCCEEDED
751731mS CMTARGET: 251.1017.0 5 jfegley.0: GetNoAnswerTimer:15
751732mS CMCallEvt: 0.1018.0 5 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
751733mS CMCallEvt: 0.1019.0 5 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
751733mS CMCallEvt: 0.1018.0 5 TargetingEP: RequestEnd 0.1019.0 5 ARS for Main
751733mS CMTARGET: 251.1017.0 5 jfegley.0: CancelTimer CMTCNoAnswerTimeout
751733mS CMCallEvt: 0.1019.0 5 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
751734mS CMARS: FORM: Main - Received Number:
751734mS CMARS: CMARSTargetingPending
751734mS CMCallEvt: 0.1018.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
751734mS CMCallEvt: 0.1018.0 -1 BaseEP: DELETE CMEndpoint f571658c TOTAL NOW=2 CALL_LIST=1
751735mS CD: CALL: 251.1017.0 BState=Idle Cut=2 Music=3.0 Aend="jfegley(2001)" (0.0) Bend="" [] (0.0) CalledNum= () CallingNum=2001 (jfegley) Internal=1 Time=4486 AState=Dialling
751736mS CMMap: a=0.0 b=0.0 pcp[162]b0r1 RTPD1
751928mS PRN: 2001: Digit Pressed 1
751929mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
751929mS CMARS: FORM: Main - Received Number: 1
751929mS CMARS: CMARSTargetingPending
751930mS CMMap: a=0.0 b=0.0 pcp[162]b0r1 RTPD0
752070mS PRN: 2001: Digit Pressed 4
752070mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
752071mS CMARS: FORM: Main - Received Number: 14
752071mS CMARS: CMARSTargetingPending
752211mS PRN: 2001: Digit Pressed 0
752211mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
752212mS CMARS: FORM: Main - Received Number: 140
752212mS CMARS: CMARSTargetingPending
752263mS RES: Thu 28/5/2009 14:02:13 FreeMem=75406796(2) CMMsg=5 (6) Buff=200 949 999 7414 5 Links=22099
752263mS RES2: RTEngine=0, CMRTEngine=0, Timer=57, Poll=0, Ready=1, CMReady=0, CMQueue=0, VPNNQueue=0
752352mS PRN: 2001: Digit Pressed 7
752352mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
752353mS CMARS: FORM: Main - Received Number: 1407
752353mS CMARS: CMARSTargetingPending
752360mS PRN: ConferDSP is alive
752493mS PRN: 2001: Digit Pressed 5
752493mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[5] Type=Default (100) Reason=CMDRdirect Keypad[5]
752494mS CMARS: FORM: Main - Received Number: 14075
752494mS CMARS: CMARSTargetingPending
752634mS PRN: 2001: Digit Pressed 1
752634mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
752635mS CMARS: FORM: Main - Received Number: 140751
752635mS CMARS: CMARSTargetingPending
752775mS PRN: 2001: Digit Pressed 7
752775mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
752776mS CMARS: FORM: Main - Received Number: 1407517
752776mS CMARS: CMARSTargetingPending
752916mS PRN: 2001: Digit Pressed 4
752916mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
752917mS CMARS: FORM: Main - Received Number: 14075174
752917mS CMARS: CMARSTargetingPending
753057mS PRN: 2001: Digit Pressed 7
753057mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
753058mS CMARS: FORM: Main - Received Number: 140751747
753058mS CMARS: CMARSTargetingPending
753198mS PRN: 2001: Digit Pressed 2
753198mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[2] Type=Default (100) Reason=CMDRdirect Keypad[2]
753199mS CMARS: FORM: Main - Received Number: 1407517472
753199mS CMARS: CMARSTargetingPending
753339mS PRN: 2001: Digit Pressed 4
753339mS CMExtnRx: v=2001, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
753340mS CMARS: FORM: Main - Received Number: 14075174724
753340mS CMARS: CMARSTargetingPending
757731mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 14075174724 Addr: f570b0dc
757731mS CMARS: FORM: Main - Received Number: 14075174724
757731mS CMARS: FOUND A SHORT CODE - short_code: 1N; - Tel: 1N - Called_Party: 14075174724 - Line Group Id: 0
757731mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=0
757732mS CMLRQ: CMLRQHandler::FindActiveLRQByGroupID GroupID=0
757732mS CMMap: a=0.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 20
757732mS CMCallEvt: 0.1020.0 -1 BaseEP: NEW CMEndpoint f5709ca0 TOTAL NOW=3 CALL_LIST=1
757738mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 14075174724 - Calling Number: 2001
757738mS CMARS: SEND Setup TO LINE
757738mS CMCallEvt: 0.1020.0 5 Q931 Trunk:1 CHAN=1: StateChange: END=child CMCSIdle->CMCSOffering
757739mS PRN: Setting configured voice gain for ch 1.
757739mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=1020 in=0
Called[14075174724] 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=1
Locale: enu
757739mS CD: CALL: 251.1017.0 BState=Idle Cut=2 Music=0.0 Aend="jfegley(2001)" (0.0) Bend="" [Line 1] (0.0) CalledNum=14075174724 () CallingNum=2001 (jfegley) Internal=1 Time=10490 AState=Dialling
757740mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=1020
757763mS RES: Thu 28/5/2009 14:02:19 FreeMem=75400980(2) CMMsg=5 (6) Buff=200 959 999 7414 5 Links=22094
757763mS RES2: RTEngine=0, CMRTEngine=0, Timer=57, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
757765mS ISDNL3Evt: v=1 stacknum=1 State, new=Proceeding, old=Initiated id=1020
757766mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=1020 in=0
BChan: slot=0 chan=1
757766mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
757766mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
757767mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.1019.0 5 ARS for Main - Call State: CMCSOverlapRecv
757767mS CMCallEvt: 0.1019.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
757768mS CMCallEvt: 0.1020.0 5 Q931 Trunk:1 CHAN=1: StateChange: END=B CMCSOffering->CMCSAccept
757769mS CMCallEvt: 251.1017.0 5 jfegley.0: StateChange: END=A CMCSDialling->CMCSDialled
757769mS CMExtnEvt: v=1 State, new=Proceeding old=Dialling,0,0,jfegley
757769mS CMExtnTx: v=2001, p1=0
CMProceeding
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=914075174724
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceISDNTrunk type=2 number=1 channel=1 rx_gain=32 tx_gain=32 ep_callid=1020 ipaddr=192.168.2.230 apps=0
Timed: 28/05/09 14:02
757771mS CMCallEvt: 0.1019.0 -1 BaseEP: DELETE CMEndpoint f570b0dc TOTAL NOW=2 CALL_LIST=1
757772mS CD: CALL: 251.1017.0 BState=Ringing Cut=3 Music=0.0 Aend="jfegley(2001)" (0.0) Bend="Line 1" [Line 1] (1.2) CalledNum=14075174724 () CallingNum=2001 (jfegley) Internal=0 Time=10522 AState=Dialled
757772mS CMMap: PCG::MapBChan pcp[162]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
757773mS CMMap: PCGS CPReserveCodec for the other end (pcp[162]b0r1) true
757773mS CMMap: PCG::MapBChan pcp[121]b1r0 cp_b f5a9cb10 other_cp_b f5ad4838 type CGTypeSimple
757773mS CMMap: a=0.1 b=1.2 M1
760365mS ISDNL3Evt: v=1 stacknum=1 State, new=ReleaseReq, old=Proceeding id=1020
760385mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=ReleaseReq id=1020
760385mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=1020 in=0
BChan: slot=0 chan=1
Cause=21, Call rejected
760385mS CMCallEvt: 0.1020.0 5 Q931 Trunk:1 CHAN=1: StateChange: END=B CMCSAccept->CMCSCompleted
760386mS CMExtnTx: v=2001, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
IE CMIEFastStartInfoData (6)
Timed: 28/05/09 14:02
760387mS CMExtnTx: v=2001, p1=8000
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1 in=1
IE CMIEFastStartInfoData (6)
760389mS CMLOGGING: CALL:2009/05/2814:02,00:00:00,000,2001,O,14075174724,914075174724,jfegley,,,0,,""n/a,0
760390mS CD: CALL: 251.1017.0 BState=Disconnecting Cut=1 Music=0.0 Aend="jfegley(2001)" (0.0) Bend="Line 1" [Line 1] (1.2) CalledNum=14075174724 () CallingNum=2001 (jfegley) Internal=0 Time=13140 AState=Dialled
760390mS CD: CALL: 251.1017.0 Deleted
760390mS CMExtnEvt: jfegley: CALL LOST (CMCauseCallRejected)
760391mS CMExtnEvt: jfegley: Extn(2001) Calling Party Number(2001) Type(CMNTypeInternal)
760391mS CMCallEvt: 251.1017.0 -1 jfegley.0: StateChange: END=X CMCSDialled->CMCSCompletedTone
760392mS CMExtnTx: v=2001, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
IE CMIEFastStartInfoData (6)
Timed: 28/05/09 14:02
760393mS CMExtnTx: v=2001, p1=8000
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1 in=1
IE CMIEFastStartInfoData (6)
760393mS CMExtnEvt: v=1 State, new=CMESCompleted old=Proceeding,0,0,jfegley
760394mS CMCallEvt: 0.1020.0 -1 Q931 Trunk:1 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
760394mS CMCallEvt: END CALL:5 (f5717568)
760395mS CMCallEvt: 0.1020.0 -1 BaseEP: DELETE CMEndpoint f5709ca0 TOTAL NOW=1 CALL_LIST=0
760398mS CMMap: PCG::UnmapBChan pcp[162]b0r1 cp_b f5ad4838 other_cp_b f5a9cb10
760398mS CMMap: a=1.2 b=0.1 M0
760398mS CMMap: PCG::UnmapBChan pcp[121]b1r0 cp_b f5a9cb10 other_cp_b 0
760399mS CMMap: PCG::AddToneGenerator g E1[7] append pcp[164]b0r1 (total 1)
760399mS CMMap: a=0.0 b=0.0 pcp[162]b0r1 RTPE1
766762mS PRN: ConferDSP is alive
767559mS CMExtnTx: v=2001, p1=8000
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1 in=1
IE CMIEFastStartInfoData (6)
767559mS CMExtnRx: v=2001, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
767559mS CMCallEvt: 251.1017.0 -1 jfegley.0: StateChange: END=X CMCSCompletedTone->CMCSCompleted
767560mS CMExtnTx: v=2001, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1017 in=0
IE CMIEFastStartInfoData (6)
Timed: 28/05/09 14:02
767560mS CMExtnEvt: jfegley: CALL LOST (CMCauseForceClear)
767561mS CMExtnEvt: jfegley: Extn(2001) Calling Party Number(2001) Type(CMNTypeInternal)
767561mS CMExtnEvt: jfegley: CMExtnHandler::SetCurrent( id: 1017->0 )
767561mS CMCallEvt: 251.1017.0 -1 jfegley.-1: StateChange: END=X CMCSCompleted->CMCSDelete
767561mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=CMESCompleted,0,0,jfegley
767562mS PRN: Destroyed MH f5717e74 parent unknown
767562mS CMTARGET: 251.1017.0 -1 BaseEP: ~CMTargetHandler
767563mS CMCallEvt: 251.1017.0 -1 BaseEP: DELETE CMEndpoint f5717f80 TOTAL NOW=0 CALL_LIST=0
767563mS CMMap: a=0.0 b=0.0 pcp[162]b0r1 RTPE0
767564mS H323Evt: RTP(END): 192.168.2.230/49152 192.168.2.72/52704 CODEC 6 PKTSZ=20 RFC2833=off AGE=20305 SENT 716 RECV 413 RTdelay=103 jitter=0 loss=0 remotejitter=18 remoteloss=0
767564mS CMMap: a=0.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 1, total 20
767763mS RES: Thu 28/5/2009 14:02:29 FreeMem=75443384(2) CMMsg=5 (6) Buff=200 958 999 7414 5 Links=22168
767763mS RES2: RTEngine=0, CMRTEngine=0, Timer=56, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
769561mS CMExtnEvt: jfegley: Recover Timer reason=CMTRWrapUp
769561mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,jfegley
769562mS CMExtnTx: v=2001, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[jfegley Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [jfegley Msgs=0]
Timed: 28/05/09 14:02

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

Does anyone see anything helpful in there? I feel like the system is very close to making successful calls but am still stuck. Any help would be greatly appreciated.

Thanks,
Joe

 

[kwing112000]

They may be rejecting it on the calling number. you are only sending them 2001 not the full 10 digit caller id. in the telephone number of your dial short codes add in sxxxxxxxxx where x is the caller id or BTN of the PRI.

Kevin Wing
ACS- Implement IP Office
ACA- Implement IP Office
Carousel Industries

 

[Mortigan]

@ kwing - That was it, I had a feeling it was a dialing rule or something similar as inbound calling is was working properly. When I made the change you recommended outbound calling began working as well.

I need to express my gratitude to everyone in this forum. I could not have made this happen without all of your valuable tips. You were all extremely patient with me even though I am clearly not fully qualified to install this phone system. The knowledge I've gained from only a couple weeks scouring these forums is worth far more to me than you all could imagine. If there's ever anything I can do to return the help I will certainly try my best to so.

THANK YOU!

-Joe

 

[quasiplanter]

I am also assuming that you have assigned the Licence for your additional 8x PRI channels.

 

[Mortigan]

@quasiplanter: I''ve actually been unsure about why it will only allow me to enable 8 of the PRI channels. In my license section it shows the license:

IP 500 VCM Channels
Valid
16 instances

However when I set any further channels on the PRI to "In Service" I get a licensing error telling me I have enabled more PRI channels than I have licenses for. Anyone have any clue?

 

[tlpeter]

Then you probably have 30 channels activated on your pri line
By default you have 8 channels and you have a license for 16 more
So the system tells you that you have 24 license and probably 30 activated

ACS - Implement IP Office
ACA - Implement IP Telephony -- ACA - Design IP Telephony
ACA - Voice Services Management
______________
Women and cats can do as they please and men and dogs should relax and get used to the idea!

 

[amriddle01]

Mortigan, those licences are for VCM channels not PRI channels

ACS - IP Office Implement

"I'm just off to Hartlepool to buy some exploding trousers

 

[tlpeter]

Oeps missed it
amriddle is right, you need pri channels for more then 8 channels

Set it to 8 for now and see if it helps

ACS - Implement IP Office
ACA - Implement IP Telephony -- ACA - Design IP Telephony
ACA - Voice Services Management
______________
Women and cats can do as they please and men and dogs should relax and get used to the idea!