Tek-Tips is the largest IT community on the Internet today!

Members share and learn making Tek-Tips Forums the best source of peer-reviewed technical information on the Internet!

  • Congratulations strongm on being selected by the Tek-Tips community for having the most helpful posts in the forums last week. Way to Go!

ARS Dial Fails when using Force Account Code with PRI30 - Account Code Dialed over trunk 1

Status
Not open for further replies.

g18c

Programmer
May 2, 2002
342
AE
I have a PRI30 (on line group 0) and client wishes to restrict international dialing with a code.

I thought force account code on a System Account code would work, I setup the code International with CLI = 10777.

I have setup system wide shortcodes of 9N, N, to an ARS (Main) that has call bar on 00 (this is the general users call bar) to block all general calls to international numbers, this is working OK.

I then created a second ARS (called International), and for specific users setup user short codes of Code = 9N, Number = N, to International ARS.

On the International ARS I have:

Code = 0N; Number = 0N, Feature=Dial, Line Group ID = 0 (Force Account Code = Disabled)
Code = 00N; Number = 00N, Feature=Dial, Line Group ID = 0, Force Account Code = Enabled

But this doesn't work, it seems the account code (10777) is being added to the dialed number (00447973100240), log snip below:

Code:
18:02:53     146236mS CD: CALL: 345.1003.0 BState=Disconnecting Cut=3 Music=0.0 Aend="Cp JackSparrow(795)" (0.0) Bend="" [] (0.0) CalledNum=90044797310024010777 () CallingNum=795 (Cp JackSparrow) Internal=1 Time=49367 AState=Disconnecting

If I remove the Force Account Code tick box it works OK - I have included the full log below, any idea what I am doing wrong please?

Code:
 18:02:12     105825mS CMARS:        FORM: Main - Received Number: 05538
 18:02:12     105825mS CMARS:        CMARSTargetingPending
 18:02:13     106162mS CMARS:        Case 1 - dial_delay_time: 3000  has expired - Resolve what we might have - Called Number: 00447973100240 Addr: f482d7a8
 18:02:13     106162mS CMARS:        FORM: International - Received Number: 00447973100240
 18:02:13     106162mS CMARS:        FOUND A SHORT CODE - short_code: 00N; - Tel: 00N - Called_Party: 00447973100240 - Line Group Id: 0
 18:02:13     106163mS CMARS:        FindActiveARSByGroupID GroupID=0 - Not Found
 18:02:13     106163mS CMLRQ:        FindActiveLRQByGroupID GroupID=0 - Not Found
 18:02:13     106163mS CMMap: a=0.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 32
 18:02:13     106163mS CMCallEvt:    0.1009.0 -1 BaseEP: NEW CMEndpoint f481f660 TOTAL NOW=5 CALL_LIST=2
 18:02:13     106164mS CMARS:        FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 00447973100240 - Calling Number: 795
 18:02:13     106164mS CMARS:        SEND Setup TO LINE
 18:02:13     106165mS CMCallEvt:    0.1009.0 1 Q931 Trunk:1 CHAN=-1: StateChange: END=child CMCSIdle->CMCSOffering
 18:02:13     106165mS CMLineTx: v=1
            CMSetup
            Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
            Called[] Type=Unknown (0) Reason=CMDRdirect  Calling[045034795] Type=Unknown Plan=Default 
            BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
            Locale: aru
 18:02:13     106166mS CD: CALL: 345.1003.0 BState=Idle Cut=2 Music=0.0 Aend="Cp JackSparrow(795)" (0.0) Bend="" [Line 1] (0.0) CalledNum=900447973100240 () CallingNum=795 (Cp JackSparrow) Internal=1 Time=9297 AState=Dialling
 18:02:13     106246mS CMExtnTx: v=795, p1=0
            CMProgress
            Line: type=IPLine 250 Call: lid=345 id=1003 in=0
            IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
            Display [ENTER ACCOUNT]
            Timed: 19/08/14 18:02
 18:02:13     106246mS CD: CALL: 345.1003.0 BState=Ringing Cut=3 Music=0.0 Aend="Cp JackSparrow(795)" (0.0) Bend="" [Line 1] (0.0) CalledNum=900447973100240 () CallingNum=795 (Cp JackSparrow) Internal=1 Time=9377 AState=Ringing
 18:02:13     106248mS CMMap: a=21.11 b=0.0 DTMF::AllocateRAS allocated CMRTVoicemail resource busy 2, total 45
 18:02:13     106248mS CMMap: a=21.11 b=1.65535 T
 18:02:13     106248mS CMMap: PCG::MapBChan pcp[119]b0r1 cp_b f5470a88 other_cp_b f5462ea8 type CGTypeDTMF
 18:02:13     106248mS CMMap: a=21.11 b=0.1 M22
 18:02:13     106248mS CMMap: PlatformConnectionAudioSAP::Bind pcp[119]b0r1 to 0.1
 18:02:13     106248mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[119]b0r1 Configure 0.1
 18:02:13     106249mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[119]b0r1 ConnectIndication 0.1
 18:02:13     106249mS H323Evt:    SetOperational 1 10.0.50.4:49152 to 1
 18:02:13     106249mS CMMap: DTMF::PlayTones g A[11] ,W,,,,,,,,
 18:02:13     106383mS PRN: 858: Digit Pressed 2

 18:02:23     116233mS ISDNL3Evt: v=1 stacknum=1  State, new=DiscInd, old=OverlapSend id=1009
 18:02:23     116234mS CMLineRx: v=1
            CMDisconnect
            Line: type=Q931Line 1 Call: lid=0 id=1009 in=0
            IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
            Cause=21, Call rejected
 18:02:23     116234mS CMARS:        LINE ep Received:  CMDisconnect - child->state = CMCSRinging - ARS Call State = CMCSAccept
 18:02:23     116234mS CMCallEvt:    0.1005.0 1 ARS for International: StateChange: END=B CMCSAccept->CMCSDisconnecting
 18:02:23     116235mS CMCallEvt:    345.1003.0 1 Cp JackSparrow.0: StateChange: END=A CMCSRingBack->CMCSICDisconnecting
 18:02:23     116235mS CMExtnEvt:    v=87 State, new=CMESCompleted  old=Alerting,0,0,Cp JackSparrow
 18:02:23     116236mS CMExtnTx: v=795, p1=0
            CMDisconnect
            Line: type=IPLine 250 Call: lid=345 id=1003 in=0
            IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
            IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=900447973100240
            IE CMIEDeviceDetail (231) LOCALE=aru HW=15 VER=9 class=CMDeviceISDNTrunk type=0 number=1 channel=1 features=0x1 rx_gain=32 tx_gain=32 ep_callid=1009 ipaddr=10.0.50.4 apps=0 loc=0 em_loc=0 features2=0x0
            Cause=21, Call rejected
            Timed: 19/08/14 18:02
 18:02:23     116236mS CD: CALL: 345.1003.0 BState=Disconnecting Cut=3 Music=0.0 Aend="Cp JackSparrow(795)" (0.0) Bend="" [Line 1] (0.0) CalledNum=90044797310024010777 () CallingNum=795 (Cp JackSparrow) Internal=1 Time=19367 AState=Disconnecting
 18:02:23     116554mS RES: Tue 19/8/2014 18:02:23 FreeMem=48219112 47889976(1) CachedMem=329136 CMMsg=6(6) Buff=5200 1361 989 7419 5 Links=23806 BTree=12806 CPU=03.53% CPUStats=1/4/5901/19966/591628/0/0 MCR=0 MCW=0
 18:02:23     116554mS RES2: IP 500 V2 9.0.3.0 build 941 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=177 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=257(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
 18:02:23     116554mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
 
 18:02:52     145586mS H323Evt:    Recv: RegistrationRequest     10.0.50.144; Endpoints registered: 120; Endpoints in registration: 0
 18:02:53     146235mS CMCallEvt:    0.1005.0 1 ARS for International: TimerExpired State=CMCSDisconnecting
 18:02:53     146235mS CMARS:        0.1005.0 1 ARS for International: DropCall() - ABOUT TO SEND CMReleaseComp
 18:02:53     146236mS CMLOGGING:     CALL:2014/08/1918:02,00:00:39,000,795,O,90044797310024010777,900447973100240,DavidMiles,,,1,,""n/a,0
 18:02:53     146236mS CD: CALL: 345.1003.0 BState=Disconnecting Cut=3 Music=0.0 Aend="Cp JackSparrow(795)" (0.0) Bend="" [] (0.0) CalledNum=90044797310024010777 () CallingNum=795 (Cp JackSparrow) Internal=1 Time=49367 AState=Disconnecting
 18:02:53     146236mS CD: CALL: 345.1003.0 Deleted
 18:02:53     146239mS CMExtnEvt:    Cp JackSparrow: CALL LOST (CMCauseCallRejected)
 18:02:53     146239mS CMExtnEvt:    Cp JackSparrow: Extn(795) Calling Party Number(795) Type(CMNTypeInternal)
 18:02:53     146239mS CMCallEvt:    345.1003.0 -1 Cp JackSparrow.0: StateChange: END=X CMCSICDisconnecting->CMCSCompleted
 18:02:53     146240mS CMExtnTx: v=795, p1=0
            CMFacility
            Line: type=IPLine 250 Call: lid=345 id=1003 in=0
            IE CMIEFastStartInfoData (6) 2 item(s)
            Timed: 19/08/14 18:02
 
Try to Set the account code as 10777 and leave the CLI blank.

 
Hi thanks for the reply, I have done that it is still sending the dialed account code out over the trunk... is it to do with enbloc ';' that i have used on the shortcode?
 
Use the "Force account code" setting on a user 900N, Dial, 00N shortcode, instead of on the ARS shortcode :)

 
Thanks for the replies so far, pulling my hair out still :(

Code = 00XXXXXXXXXXXN
Feature = Dial
Number = 00N
Line Group = 0
Force account code = y
Locale = blank

If force account code = Yes it doesn't work.
If force account code = No, then it works!

The number is being sent out of the trunk correctly (0035722369900), I have attached traces of force account code on, and off, below:

The trace is below with force account code = y (doesn't work)

Code:
 09:44:35  143059145mS CMExtnRx: v=807, p1=0
            CMInformation
            Line: type=IPLine 250 Call: lid=357 id=8834 in=0
            Called[0] Type=Default (100) Reason=CMDRdirect  Keypad[0] 
 09:44:35  143059145mS CMTARGET:     357.8834.0 2221 Extn807.0: Setting Hard Timer 3000
 09:44:35  143059146mS CMTARGET:     357.8834.0 2221 Extn807.0: LOOKUP CALL ROUTE: type=100 called_party=0035722369900 sub= calling=807 dir=out complete=0 ses=0
 09:44:35  143059146mS CMTARGET:     357.8834.0 2221 Extn807.0: ADD TARGET (N): number=0035722369900 type=100 depth=1 nobar=1 setorig=1 ses=0
 09:44:35  143059146mS CMTARGET:     357.8834.0 2221 Extn807.0: USER SC: 0035722369900 14 35722369900 sc=type=Dial code=00XXXXXXXXXXXN, num=00N
 09:44:35  143059147mS CMARS:        FindActiveARSByGroupID GroupID=0 - Not Found
 09:44:35  143059147mS CMLRQ:        FindActiveLRQByGroupID GroupID=0 - Not Found
 09:44:35  143059147mS CMMap: a=0.17 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 4, total 32
 09:44:35  143059148mS CMCallEvt:    0.8836.0 -1 BaseEP: NEW CMEndpoint f47ad758 TOTAL NOW=11 CALL_LIST=5
 09:44:35  143059150mS CMTARGET:     DIAL LINE: 2221 GROUP = 0 SUCCESS = f47ad758
 09:44:35  143059150mS CMTARGET:     357.8834.0 2221 Extn807.0: CancelTimer CMTCDelayedProcessing
 09:44:35  143059150mS CMTARGET:     357.8834.0 2221 Extn807.0: INITIAL TARGETING SUCCEEDED
 09:44:35  143059150mS CMTARGET:     357.8834.0 2221 Extn807.0: GetNoAnswerTimer:15
 09:44:35  143059150mS CMCallEvt:    0.8835.0 2221 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
 09:44:35  143059152mS CMCallEvt:    0.8836.0 2221 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
 
 09:44:35  143059153mS CD: CALL: 357.8834.0 BState=Idle Cut=0 Music=0.0 Aend="Extn807(807)" (0.0) Bend="Line 1" [Line 1] (0.0) CalledNum=0035722369900 () CallingNum=807 (Extn807) Internal=0 Time=4810 AState=Dialling
 09:44:35  143059154mS ISDNL3Evt: v=1 stacknum=1  State, new=Initiated, old=NullState id=8836
 09:44:35  143059222mS ISDNL3Evt: v=1 stacknum=1  State, new=OverlapSend, old=Initiated id=8836
 09:44:35  143059223mS CMLineRx: v=1
            CMSetupAck
            Line: type=Q931Line 1 Call: lid=0 id=8836 in=0
            BChan: slot=0 chan=13
            IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
 09:44:35  143059225mS CMCallEvt:    0.8835.0 2221 TargetingEP: RequestEnd 0.8836.0 2221 Q931 Trunk:1 CHAN=13
 09:44:35  143059225mS CMTARGET:     357.8834.0 2221 Extn807.0: CancelTimer CMTCNoAnswerTimeout
 09:44:35  143059225mS CMCallEvt:    0.8835.0 -1 BaseEP: DELETE CMEndpoint f47be4f0 TOTAL NOW=10 CALL_LIST=5
 09:44:35  143059226mS CMCallEvt:    357.8834.0 2221 Extn807.0: StateChange: END=A CMCSDialling->CMCSRingBack
 09:44:35  143059227mS CMExtnEvt:    v=104 State, new=Alerting  old=Dialling,0,0,Extn807
 09:44:35  143059228mS CMExtnTx: v=807, p1=0
            CMProgress
            Line: type=IPLine 250 Call: lid=357 id=8834 in=0
            IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
            Display [ENTER ACCOUNT]
            Timed: 21/08/14 09:44
 09:44:35  143059230mS CD: CALL: 357.8834.0 BState=Idle Cut=3 Music=0.0 Aend="Extn807(807)" (0.0) Bend="Line 1" [Line 1] (1.14) CalledNum=0035722369900 () CallingNum=807 (Extn807) Internal=0 Time=4887 AState=Ringing
 09:44:35  143059231mS CMMap: a=21.11 b=0.0 DTMF::AllocateRAS allocated CMRTVoicemail resource busy 2, total 45
 09:44:35  143059231mS CMMap: a=21.11 b=1.65535 T
 09:44:35  143059232mS CMMap: PCG::MapBChan pcp[961]b0r1 cp_b f546d5f8 other_cp_b f5462ea8 type CGTypeDTMF
 09:44:35  143059232mS CMMap: a=21.11 b=0.17 M22
 09:44:35  143059232mS CMMap: PlatformConnectionAudioSAP::Bind pcp[961]b0r1 to 0.17
 09:44:35  143059232mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[961]b0r1 Configure 0.17
 09:44:35  143059232mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[961]b0r1 ConnectIndication 0.17
 09:44:35  143059232mS H323Evt:    SetOperational 2129 10.0.50.4:49158 to 1
 09:44:35  143059232mS CMMap: DTMF::PlayTones g A[11] ,W,,,,,,,,
 09:44:35  143059233mS CMMap: PCG::MapBChan pcp[101]b1r0 cp_b f541ed58 other_cp_b 0 type CGTypeSimple
 09:44:45  143069223mS ISDNL3Evt: v=1 stacknum=1  State, new=DiscInd, old=OverlapSend id=8836
 09:44:45  143069224mS CMLineRx: v=1
            CMDisconnect
            Line: type=Q931Line 1 Call: lid=0 id=8836 in=0
            IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
            Cause=21, Call rejected
 09:44:45  143069224mS CMCallEvt:    0.8836.0 2221 Q931 Trunk:1 CHAN=13: StateChange: END=B CMCSOffering->CMCSDisconnecting
 09:44:45  143069225mS CMCallEvt:    357.8834.0 2221 Extn807.0: StateChange: END=A CMCSRingBack->CMCSICDisconnecting
 09:44:45  143069226mS CMExtnEvt:    v=104 State, new=CMESCompleted  old=Alerting,0,0,Extn807
 09:44:45  143069226mS CMExtnTx: v=807, p1=0
            CMDisconnect
            Line: type=IPLine 250 Call: lid=357 id=8834 in=0
            IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
            IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=0035722369900
            IE CMIEDeviceDetail (231) LOCALE=aru HW=15 VER=9 class=CMDeviceISDNTrunk type=0 number=1 channel=13 features=0x0 rx_gain=32 tx_gain=32 ep_callid=8836 ipaddr=10.0.50.4 apps=0 loc=0 em_loc=0 features2=0x0
            Cause=21, Call rejected
            Timed: 21/08/14 09:44
 09:44:45  143069226mS CD: CALL: 357.8834.0 BState=Disconnecting Cut=3 Music=0.0 Aend="Extn807(807)" (0.0) Bend="Line 1" [Line 1] (1.14) CalledNum=0035722369900 () CallingNum=807 (Extn807) Internal=0 Time=14883 AState=Disconnecting

The trace is below with force account code = n (working OK):

Code:
 10:22:00  145303330mS CMCallEvt:    0.9151.0 2313 TargetingEP: RequestEnd 0.9152.0 2313 Q931 Trunk:1 CHAN=11
 10:22:00  145303330mS CMTARGET:     357.9150.0 2313 Extn807.0: CancelTimer CMTCNoAnswerTimeout
 10:22:00  145303331mS CMCallEvt:    0.9151.0 -1 BaseEP: DELETE CMEndpoint f475a3e8 TOTAL NOW=10 CALL_LIST=5
 10:22:00  145303332mS CMCallEvt:    0.9152.0 2313 Q931 Trunk:1 CHAN=11: StateChange: END=B CMCSOffering->CMCSOverlapRecv
 10:22:00  145303333mS CD: CALL: 357.9150.0 BState=Idle Cut=2 Music=0.0 Aend="Extn807(807)" (0.0) Bend="Line 1" [Line 1] (1.12) CalledNum=0035722369900 () CallingNum=807 (Extn807) Internal=0 Time=5880 AState=Dialling
 10:22:00  145303334mS CMMap: PCG::MapBChan pcp[279]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
 10:22:00  145303334mS CMMap: PCGS CPReserveCodec for the other end (pcp[279]b0r1) true
 10:22:00  145303334mS CMMap: PCG::MapBChan pcp[99]b1r0 cp_b f541f3e8 other_cp_b f5470458 type CGTypeSimple
 10:22:00  145303335mS CMMap: a=0.3 b=1.12 M12
 10:22:00  145303335mS CMMap: PlatformConnectionAudioSAP::Bind pcp[279]b0r1 to 0.3
 10:22:00  145303335mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[279]b0r1 Configure 0.3
 10:22:00  145303335mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelay ID: 4
 10:22:00  145303335mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success
 10:22:00  145303335mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 10.0.50.4:49154, Remote IP addr 10.0.50.161:49814 
 10:22:00  145303335mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.3:32772, telogyHost IP addr 1.1.1.1:14 
 10:22:00  145303335mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Start success
 10:22:00  145303335mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[279]b0r1 ConnectIndication 0.3
 10:22:06  145310667mS ISDNL3Evt: v=1 stacknum=1  State, new=Delivered, old=OverlapSend id=9152
 10:22:06  145310668mS CMLineRx: v=1
            CMAlerting
            Line: type=Q931Line 1 Call: lid=0 id=9152 in=0
            IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
 10:22:06  145310669mS CMCallEvt:    0.9152.0 2313 Q931 Trunk:1 CHAN=11: StateChange: END=B CMCSOverlapRecv->CMCSRinging
 10:22:06  145310670mS CMCallEvt:    357.9150.0 2313 Extn807.0: StateChange: END=A CMCSDialling->CMCSRingBack
 10:22:06  145310671mS CMExtnEvt:    v=104 State, new=Alerting  old=Dialling,0,0,Extn807
 10:22:06  145310671mS CMExtnTx: v=807, p1=0
            CMAlerting
            Line: type=IPLine 250 Call: lid=357 id=9150 in=0
            IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDInbandPattern (8)
            IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=0035722369900
            IE CMIEDeviceDetail (231) LOCALE=aru HW=15 VER=9 class=CMDeviceISDNTrunk type=0 number=1 channel=11 features=0x0 rx_gain=32 tx_gain=32 ep_callid=9152 ipaddr=10.0.50.4 apps=0 loc=0 em_loc=0 features2=0x0
            Timed: 21/08/14 10:22
 10:22:06  145310676mS CD: CALL: 357.9150.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn807(807)" (0.0) Bend="Line 1" [Line 1] (1.12) CalledNum=0035722369900 () CallingNum=807 (Extn807) Internal=0 Time=13222 AState=Ringing
 10:22:06  145310677mS CMMap: a=1.12 b=0.0 PCGS CPReserveCodec (pcp[279]b0r1) true
 10:22:06  145310677mS CMMap: a=1.12 b=0.0 PCGS CPReserveCodec (pcp[279]b0r1) true
 10:22:06  145310677mS CMMap: PCG::MapBChan pcp[279]b0r1 cp_b f5470458 other_cp_b f541f3e8 type CGTypeSimple
 10:22:06  145310677mS CMMap: a=1.12 b=0.3 M12
 10:22:07  145311012mS RES: Thu 21/8/2014 10:22:07 FreeMem=47840032 46787464(1) CachedMem=1052568 CMMsg=8(8) Buff=5200 1364 998 7418 5 Links=51234 BTree=12789 CPU=09.51% CPUStats=8/16/4337/18524/20813/1/0 MCR=0 MCW=0
 10:22:07  145311012mS RES2: IP 500 V2 9.0.3.0 build 941 Tasks=48 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=299 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=2 TCP=254(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
 10:22:07  145311012mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
 10:22:12  145316286mS CMExtnTx: v=807, p1=8106
            CMFacility
            Line: type=IPLine 250 Call: lid=357 id=104 in=1
            IE CMIEFastStartInfoData (6) 2 item(s)
 10:22:12  145316286mS CMExtnRx: v=807, p1=0
            CMReleaseComp
            Line: type=IPLine 250 Call: lid=357 id=9150 in=0
 10:22:12  145316286mS CMCallEvt:    357.9150.0 2313 Extn807.0: StateChange: END=A CMCSRingBack->CMCSCompleted
 10:22:12  145316287mS CMExtnEvt:    v=104 State, new=PortRecoverDelay  old=Alerting,0,0,Extn807
 10:22:12  145316289mS CMExtnTx: v=807, p1=0
            CMFacility
            Line: type=IPLine 250 Call: lid=357 id=9150 in=0
            IE CMIEFastStartInfoData (6) 2 item(s)
            Timed: 21/08/14 10:22
 10:22:12  145316290mS CMLOGGING:     CALL:2014/08/2110:21,00:00:00,005,807,O,0035722369900,0035722369900,Extn807,,,0,,""n/a,0
 10:22:12  145316290mS CD: CALL: 357.9150.0 BState=Ringing Cut=2 Music=0.0 Aend="ELine 1" (0.0) Bend="Line 1" [Line 1] (1.12) CalledNum=0035722369900 () CallingNum=807 (Extn807) Internal=0 Time=18837 AState=Idle
 10:22:12  145316291mS CD: CALL: 357.9150.0 Deleted
 10:22:12  145316293mS CMExtnEvt:    Extn807: CALL LOST (CMCauseNormal)
 10:22:12  145316293mS CMExtnEvt:    Extn807: Extn(807) Calling Party Number(807) Type(CMNTypeInternal)
 10:22:12  145316293mS CMExtnEvt:    Extn807: CMExtnHandler::SetCurrent( id: 9150->0 )
 10:22:12  145316295mS CMCallEvt:    357.9150.0 -1 Extn807.-1: StateChange: END=X CMCSCompleted->CMCSDelete
 10:22:12  145316297mS CMLineTx: v=1
            CMDisconnect
            Line: type=Q931Line 1 Call: lid=0 id=9152 in=0
            Cause=16, Normal call clearing
 10:22:12  145316297mS CMCallEvt:    0.9152.0 -1 Q931 Trunk:1 CHAN=11: StateChange: END=X CMCSRinging->CMCSCompleted
 10:22:12  145316297mS CMCallEvt:    END CALL:2313 (f48290f8)
 10:22:12  145316299mS CMTARGET:     357.9150.0 -1 BaseEP: ~CMTargetHandler f4764df8 ep f477a2e8
 10:22:12  145316299mS CMCallEvt:    357.9150.0 -1 BaseEP: DELETE CMEndpoint f477a2e8 TOTAL NOW=9 CALL_LIST=4
 10:22:12  145316301mS CMMap: PCG::UnmapBChan pcp[99]b1r0 cp_b f541f3e8 other_cp_b f5470458
 10:22:12  145316301mS CMMap: a=0.3 b=1.12 M02
 10:22:12  145316301mS CMMap: PlatformConnectionAudioSAP::DisconnectVoice rtpRelay ID: 4 Close success
 10:22:12  145316301mS H323Evt:    SetOperational 2217 10.0.50.4:49154 to 0
 10:22:12  145316301mS CMMap: PCG::UnmapBChan pcp[279]b0r1 cp_b 0 other_cp_b 0
 10:22:12  145316302mS H323Evt:    RTP(END): 10.0.50.4/49154 10.0.50.161/49814 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=18833 SENT=700 RECV=681 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
 10:22:12  145316303mS CMMap: a=0.3 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 5, total 32
 10:22:12  145316304mS ISDNL3Evt: v=1 stacknum=1  State, new=DiscReq, old=Delivered id=9152
 10:22:12  145316491mS ISDNL3Evt: v=1 stacknum=1  State, new=NullState, old=DiscReq id=9152
 10:22:12  145316491mS CMLineRx: v=1
            CMReleaseComp
            Line: type=Q931Line 1 Call: lid=0 id=9152 in=0
            BChan: slot=0 chan=11
 10:22:12  145316492mS CMCallEvt:    0.9152.0 -1 Q931 Trunk:1 CHAN=11: StateChange: END=X CMCSCompleted->CMCSDelete
 10:22:12  145316492mS CMCallEvt:    0.9152.0 -1 BaseEP: DELETE CMEndpoint f47b1028 TOTAL NOW=8 CALL_LIST=4
 10:22:12  145316512mS RES: Thu 21/8/2014 10:22:12 FreeMem=47907528 46787464(1) CachedMem=1120064 CMMsg=8(8) Buff=5200 1361 998 7418 5 Links=51349 BTree=12791 CPU=06.99% CPUStats=1/4/4337/18953/20813/1/0 MCR=0 MCW=0
 10:22:12  145316512mS RES2: IP 500 V2 9.0.3.0 build 941 Tasks=48 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=300 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=2 TCP=254(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034

My system telephony settings are:
Dial delay count = 0
Dial delay time = 3
 
Ok, so after dispatching an engineer to site i have found the issue.

Despite user insisting they were pressing the # key after the account code (i asked a number of times), it turns out they were not.

Pressing # to complete the account code with the above shortcodes works.

I guess what i was seeing in the logs is the system holding the trunk in stanndby whilst waiting for a valid account code, and after 10 seconds dropping the call due to no short code.

Sorry for the headache guys
 
hah, hate when that happens. The simplest thing is the solution.

Have a star for posting the solution to the problem.
 
Status
Not open for further replies.

Part and Inventory Search

Sponsor

Back
Top