Issues Forwarding with Access Codes

[sgrossman]

Hello Tek-Tips Enthusiasts,

I have a customer with IP Office running version 4.2(4). They use access codes to dial long distance. In the user tab, I have setup forwarding to a short code of *671. In the short code field I have the following.

*671
Dial
1xxxxxxxxxxYzzzz
1

Where xxxxxxxxxx is the phone number, zzzz is the access code, and 1 is the group ID of the PRI. When the call is received it returns just a busy tone.

Any ideas?

Thanks

 

[Shortcode]

Try 1xxxxxxxxxx,zzzz

Make sure the comma is used between the number and the code. The system will not generate DTMF on the call until after the pause.

"When you do things right, people won't be sure you have done anything at all.

 

[sgrossman]

I have tried that as well as using "D". None of these seem to work.

 

[Shortcode]

What is requesting the code? The IP Office or the PRI?
Can you get a trace of the attempt?

"When you do things right, people won't be sure you have done anything at all.

 

[sgrossman]

The PRI is requesting the code. I can get a trace of it tomorrow morning when I am working on it again.

 

[Shortcode]

Cool. Trace it while using the code with a comma. Make sure you have your ISDN Tx and Rx layers selected.

"When you do things right, people won't be sure you have done anything at all.

 

[sgrossman]

Ok, here goes the results:

When I used a , (comma) this is what I got out of the trace:

UNICODE-UTF8
enu
4200590097mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[CLAIM] Type=ACD (103) Reason=CMDRdirect
4200590108mS CD: CALL: 9.20.1 BState=Connected Cut=3 Music=0.0 Aend="Line 9" (5.2) Bend="Confluence Main Route(Confluence Main Route)" [VoiceMail] (21.9) CalledNum=Confluence Main Route () CallingNum=93016703228 (BENCOMM) Internal=0 Time=8169 AState=Connec4200590109mS CMExtnRx: v=RAS, p1=0
4200590109mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[CLAIM] Type=ACD (103) Reason=CMDRdirect
4200590109mS CMExtnRx: v=RAS, p1=0
CMTransfer
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[84] Type=Default (100) Reason=CMDRdirect SndComp
4200590109mS CMCallEvt: 0.122583.0 33472 RAS.0: Transfer CMCauseTransfer
4200590110mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: ADD TARGET (N): number=84 type=100 depth=1 nobar=1 setorig=1 ses=0
4200590110mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: SET USER: Joe Tenne orig=1
4200590110mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: ADD USER: Joe Tenne depth=2 disallow_cw=0 dnd=0 real_call=1 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000
4200590111mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: ADD TARGET (N): number=*671 type=100 depth=3 nobar=1 setorig=0 ses=0
4200590111mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: SYS SC: *671 4 sc=type=Dial code=*671, num=13018006939,4412 callinfop->sending_complete=1 secondary_dialtone=
4200590112mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=1
4200590112mS CMLRQ: CMLRQHandler::FindActiveLRQByGroupID GroupID=1
4200590112mS CMCallEvt: 0.122584.0 -1 BaseEP: NEW CMEndpoint f554b1a0 TOTAL NOW=9 CALL_LIST=4
4200590115mS CMTARGET: DIAL LINE: 33472 GROUP = 1 SUCCESS = f554b1a0
4200590115mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: PrepareTransferTargets Found 1 target
4200590115mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: GetNoAnswerTimer:15
4200590116mS CMCallEvt: 0.122585.0 -1 BaseEP: NEW CMEndpoint f550794c TOTAL NOW=10 CALL_LIST=4
4200590117mS CMExtnEvt: RAS: CALL LOST (CMCauseTransfer)
4200590117mS CMCallEvt: 0.122583.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
4200590117mS CMExtnEvt: v=1008 State, new=CMESCompleted old=Connected,0,0,RAS
4200590118mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1008 in=0
Called[Confluence Main Route] Type=Voicemail (102) Reason=CMDRX_Attendant Calling[93016703228] Type=Unknown Plan=ISDN Pres=Allowed (0)
BChan: slot=21 chan=9
Cause=126, Transfer(IPO)
4200590118mS CMMap: a=21.9 b=0.0 T0
4200590119mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 122583->0 )
4200590119mS CMCallEvt: 0.122583.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
4200590119mS CMExtnEvt: v=1008 State, new=Idle old=CMESCompleted,0,0,RAS
4200590119mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1008 in=0
BChan: slot=21 chan=9
Cause=126, Transfer(IPO)
4200590119mS CMCallEvt: 0.122585.0 33472 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
4200590121mS CMCallEvt: 0.122584.0 33472 Q931 Trunk:9 CHAN=20: StateChange: END=T CMCSIdle->CMCSOffering
4200590121mS PRN: Setting configured voice gain for ch 20.
4200590121mS CMLineTx: v=9
CMSetup
Line: type=Q931Line 9 Call: lid=0 id=122584 in=0
Called[13018006939] Type=National (2) Reason=CMDRdirect Calling[] Type=National Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=20
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=4065
Timed: 27/03/12 14:08
Locale: enu
4200590123mS CMCallEvt: 0.122583.0 -1 BaseEP: DELETE CMEndpoint f55171dc TOTAL NOW=9 CALL_LIST=4
4200590123mS CMMap: PCG::UnmapBChan pcp[41]b1r0 cp_b f59a4554 other_cp_b f5967aa4
4200590123mS CMMap: a=5.2 b=21.9 M0
4200590124mS CMMap: PCG::UnmapBChan pcp[98]b1r0 cp_b f5967aa4 other_cp_b 0
4200590124mS CD: CALL: 9.20.1 BState=Idle Cut=1 Music=0.0 Aend="Line 9" (5.2) Bend="Joe Tenne(84)" [Line 9] (0.0) CalledNum=84 (Joe Tenne) CallingNum=93016703228 (BENCOMM) Internal=0 Time=8185 AState=Connected
4200590126mS ISDNL3Evt: v=9 stacknum=9 State, new=Initiated, old=NullState id=122584
4200590187mS ISDNL3Evt: v=9 stacknum=9 State, new=Proceeding, old=Initiated id=122584
4200590188mS CMLineRx: v=9
CMProceeding
Line: type=Q931Line 9 Call: lid=0 id=122584 in=0
BChan: slot=0 chan=20
4200590188mS CMCallEvt: 0.122584.0 33472 Q931 Trunk:9 CHAN=20: StateChange: END=T CMCSOffering->CMCSAccept
4200590189mS CMCallEvt: 0.122585.0 33472 TargetingEP: StateChange: END=B CMCSOffering->CMCSAccept
4200590189mS CD: CALL: 9.20.1 BState=Ringing Cut=1 Music=0.0 Aend="Line 9" (5.2) Bend="Joe Tenne(84)" [Line 9] (0.0) CalledNum=84 (Joe Tenne) CallingNum=93016703228 (BENCOMM) Internal=0 Time=8250 AState=Connected
4200590368mS CMLineRx: v=9
CMProgress
Line: type=Q931Line 9 Call: lid=0 id=122584 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDNotISDN (1)
Cause=170, Interworking Unspecified(IPO)
4200590369mS CMCallEvt: 0.122584.0 33472 Q931 Trunk:9 CHAN=20: StateChange: END=T CMCSAccept->CMCSRinging
4200590369mS CMCallEvt: 0.122585.0 33472 TargetingEP: StateChange: END=B CMCSAccept->CMCSRinging
4200590370mS CD: CALL: 9.20.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 9" (5.2) Bend="Joe Tenne(84)" [Line 9] (0.0) CalledNum=84 (Joe Tenne) CallingNum=93016703228 (BENCOMM) Internal=0 Time=8431 AState=Connected
4200590371mS CMMap: a=5.2 b=0.0 R1
4200590420mS RES: Tue 27/3/2012 14:08:37 FreeMem=74180656(1) CMMsg=6 (8) Buff=200 965 999 7414 5 Links=12916
4200590420mS RES2: RTEngine=0, CMRTEngine=0, Timer=62, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
4200598739mS PRN: ConferDSP is alive
4200605115mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: TimerExpired cause=CMTCNoAnswerTimeout
4200605115mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: Retarget NOANSWER EXCEPTED=00000008 ValidTargets=8
4200605115mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: Retarget on target_cfg_user=Joe Tenne
4200605116mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: ADD USER: Joe Tenne depth=1 disallow_cw=0 dnd=0 real_call=1 type(CMNTypeDefault) incl(0x8) excpt(0x8), allow_redir(1) remote=00000000
4200605116mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: SELECT: TRY VOICEMAIL orig_hg() orig_user(84)
4200605116mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: ADD VM TARGET
4200605116mS CMCallEvt: 0.122586.0 -1 BaseEP: NEW CMEndpoint f55171dc TOTAL NOW=10 CALL_LIST=4
4200605117mS CMTARGET: 0.122586.0 33472 RAS.0: ADD PRIMARY
4200605117mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: ADD VM TARGET: SUCCEEDED
4200605117mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: GetNoAnswerTimer:15
4200605118mS CMCallEvt: 0.122586.0 33472 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
4200605118mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->122586 )
4200605118mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1008 in=0
Called[#Joe Tenne] Type=Voicemail (102) Reason=CMDRX_MailboxAccess SndComp Calling[93016703228] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=9
IE CMIECalledPartyName (224) Joe Tenne
IE CMIECalledPartyKName (225) Joe Tenne
IE CMIECallingPartyName (110) BENCOMM
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=4065
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIEOriginalCalledPartyName (236) Joe Tenne
IE CMIEOriginalCalledPartyKName (237) Joe Tenne
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:3) number=84
IE CMIERedirectingPartyName (232) Joe Tenne
IE CMIERedirectingPartyKName (233) Joe Tenne
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=84
IE CMIERespondingPartyName (228) BENCOMM
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:1 R:4) number=93016703228
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceISDNTrunk type=2 number=9 channel=1 rx_gain=32 tx_gain=32 ep_callid=122581 ipaddr=192.168.0.221 apps=0
Display [BENCOMM>Joe Tenne]
Timed: 27/03/12 14:08
Locale: enu
4200605119mS CMMap: a=21.9 b=1.254 T
4200605120mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228) #Joe Tenne
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceVoicemail type=0 number=0 channel=9 rx_gain=32 tx_gain=32 ep_callid=122586 ipaddr=192.168.0.221 apps=0
IE CMIERedirectingPartyName (232) Joe Tenne
IE CMIERedirectingPartyKName (233) Joe Tenne
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=84
4200605120mS CMCallEvt: 0.122586.0 33472 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
4200605120mS CMCallEvt: 0.122585.0 33472 TargetingEP: RequestEnd 0.122586.0 33472 RAS.0
4200605120mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: CancelTimer CMTCNoAnswerTimeout
4200605123mS CMLineTx: v=9
CMDisconnect
Line: type=Q931Line 9 Call: lid=0 id=122584 in=0
Cause=123, Forward To Voicemail(IPO)
4200605123mS CMCallEvt: 0.122584.0 -1 Q931 Trunk:9 CHAN=20: StateChange: END=X CMCSRinging->CMCSCompleted
4200605124mS CMExtnEvt: v=1008 State, new=Connected old=Idle,0,0,RAS
4200605124mS CMCallEvt: 0.122586.0 33472 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
4200605124mS CMTARGET: 9.20.1 33472 Q931 Trunk:9 CHAN=1: ~CMTargetHandler
4200605125mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1008 in=0
BChan: slot=21 chan=9
IE CMIERespondingPartyName (228) BENCOMM
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:1 R:4) number=93016703228
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceISDNTrunk type=2 number=9 channel=1 rx_gain=32 tx_gain=32 ep_callid=122581 ipaddr=192.168.0.221 apps=0
IE CMIERedirectingPartyName (232) Joe Tenne
IE CMIERedirectingPartyKName (233) Joe Tenne
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=84
4200605126mS CMCallEvt: 0.122585.0 -1 BaseEP: DELETE CMEndpoint f550794c TOTAL NOW=9 CALL_LIST=4
4200605126mS CD: CALL: 9.20.1 BState=Connected Cut=3 Music=0.0 Aend="Line 9" (5.2) Bend="Joe Tenne(84)" [VoiceMail] (21.9) CalledNum=#Joe Tenne (Joe Tenne) CallingNum=93016703228 (BENCOMM) Internal=0 Time=23187 AState=Connected
4200605127mS CMMap: a=5.2 b=0.0 R0
4200605128mS CMMap: PCG::MapBChan pcp[98]b1r0 cp_b f5967aa4 other_cp_b 0 type CGTypeSimple
4200605128mS CMMap: PCG::MapBChan pcp[41]b1r0 cp_b f59a4554 other_cp_b f5967aa4 type CGTypeSimple
4200605128mS CMMap: a=5.2 b=21.9 M1
4200605129mS ISDNL3Evt: v=9 stacknum=9 State, new=DiscReq, old=Proceeding id=122584
4200605153mS ISDNL3Evt: v=9 stacknum=9 State, new=NullState, old=DiscReq id=122584
4200605154mS CMLineRx: v=9
CMReleaseComp
Line: type=Q931Line 9 Call: lid=0 id=122584 in=0
BChan: slot=0 chan=20
4200605154mS CMCallEvt: 0.122584.0 -1 Q931 Trunk:9 CHAN=20: StateChange: END=X CMCSCompleted->CMCSDelete
4200605155mS CMCallEvt: 0.122584.0 -1 BaseEP: DELETE CMEndpoint f554b1a0 TOTAL NOW=8 CALL_LIST=4
4200605420mS RES: Tue 27/3/2012 14:08:52 FreeMem=74187772(1) CMMsg=6 (8) Buff=200 965 999 7407 5 Links=12909
4200605420mS RES2: RTEngine=0, CMRTEngine=0, Timer=60, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
4200609682mS ISDNL3Evt: v=9 stacknum=9 State, new=ReleaseReq, old=Active id=20
4200609702mS ISDNL3Evt: v=9 stacknum=9 State, new=NullState, old=ReleaseReq id=20
4200609703mS CMLineRx: v=9
CMReleaseComp
Line: type=Q931Line 9 Call: lid=9 id=20 in=1
BChan: slot=0 chan=1
Cause=16, Normal call clearing
4200609703mS CMCallEvt: 9.20.1 33472 Q931 Trunk:9 CHAN=1: StateChange: END=A CMCSConnected->CMCSCompleted
4200609706mS CMLOGGING: CALL:2012/03/2714:08,00:00:27,014,93016703228,I,84,4065,BENCOMM,,,0,,""n/a,0
4200609706mS CD: CALL: 9.20.1 BState=Connected Cut=2 Music=0.0 Aend="Line 9" (5.2) Bend="Joe Tenne(84)" [VoiceMail] (21.9) CalledNum=#Joe Tenne (Joe Tenne) CallingNum=93016703228 (BENCOMM) Internal=0 Time=27767 AState=Idle
4200609706mS CD: CALL: 9.20.1 Deleted
4200609706mS CMCallEvt: 9.20.1 -1 Q931 Trunk:9 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
4200609707mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
4200609707mS CMCallEvt: 0.122586.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
4200609707mS CMExtnEvt: v=1008 State, new=CMESCompleted old=Connected,0,0,RAS
4200609708mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1008 in=0
Called[#Joe Tenne] Type=Voicemail (102) Reason=CMDRX_MailboxAccess Calling[93016703228] Type=Unknown Plan=ISDN Pres=Allowed (0)
BChan: slot=21 chan=9
Cause=16, Normal call clearing
4200609708mS CMMap: a=21.9 b=0.0 T0
4200609709mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 122586->0 )
4200609709mS CMCallEvt: 0.122586.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
4200609709mS CMExtnEvt: v=1008 State, new=Idle old=CMESCompleted,0,0,RAS
4200609709mS CMCallEvt: 0.122586.0 -1 BaseEP: DELETE CMEndpoint f55171dc TOTAL NOW=7 CALL_LIST=3
4200609710mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1008 in=0
BChan: slot=21 chan=9
Cause=16, Normal call clearing
4200609710mS CMCallEvt: END CALL:33472 (f5578370)
4200609711mS CMCallEvt: 9.20.1 -1 BaseEP: DELETE CMEndpoint f557bf90 TOTAL NOW=6 CALL_LIST=3
4200609712mS CMMap: PCG::UnmapBChan pcp[41]b1r0 cp_b f59a4554 other_cp_b f5967aa4
4200609712mS CMMap: a=5.2 b=21.9 M0
4200609712mS CMMap: PCG::UnmapBChan pcp[98]b1r0 cp_b f5967aa4 other_cp_b 0
4200609734mS CMExtnTx: v=84, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 7 Call: lid=0 id=-1 in=0
Called[Joe Tenne Msgs=0 Old=3 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Joe Tenne Msgs=0]
Timed: 27/03/12 14:08

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

I then used a Y in the short code and received this trace:

UNICODE-UTF8
enu
4200757344mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[CLAIM] Type=ACD (103) Reason=CMDRdirect
4200757354mS CD: CALL: 9.9.1 BState=Connected Cut=3 Music=0.0 Aend="Line 9" (5.2) Bend="Confluence Main Route(Confluence Main Route)" [VoiceMail] (21.9) CalledNum=Confluence Main Route () CallingNum=93016703228 (BENCOMM) Internal=0 Time=6537 AState=Connect4200757355mS CMExtnRx: v=RAS, p1=0
4200757355mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[CLAIM] Type=ACD (103) Reason=CMDRdirect
4200757356mS CMExtnRx: v=RAS, p1=0
CMTransfer
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[84] Type=Default (100) Reason=CMDRdirect SndComp
4200757356mS CMCallEvt: 0.122589.0 33473 RAS.0: Transfer CMCauseTransfer
4200757356mS CMTARGET: 9.9.1 33473 Q931 Trunk:9 CHAN=1: ADD TARGET (N): number=84 type=100 depth=1 nobar=1 setorig=1 ses=0
4200757356mS CMTARGET: 9.9.1 33473 Q931 Trunk:9 CHAN=1: SET USER: Joe Tenne orig=1
4200757357mS CMTARGET: 9.9.1 33473 Q931 Trunk:9 CHAN=1: ADD USER: Joe Tenne depth=2 disallow_cw=0 dnd=0 real_call=1 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000
4200757357mS CMTARGET: 9.9.1 33473 Q931 Trunk:9 CHAN=1: ADD TARGET (N): number=*671 type=100 depth=3 nobar=1 setorig=0 ses=0
4200757357mS CMTARGET: 9.9.1 33473 Q931 Trunk:9 CHAN=1: SYS SC: *671 4 sc=type=Dial code=*671, num=13018006939Y4412 callinfop->sending_complete=1 secondary_dialtone=
4200757358mS CMARS: CMARSHandler::FindActiveARSByGroupID GroupID=1
4200757358mS CMLRQ: CMLRQHandler::FindActiveLRQByGroupID GroupID=1
4200757358mS CMCallEvt: 0.122590.0 -1 BaseEP: NEW CMEndpoint f554b1a0 TOTAL NOW=9 CALL_LIST=4
4200757360mS CMTARGET: DIAL LINE: 33473 GROUP = 1 SUCCESS = f554b1a0
4200757360mS CMTARGET: 9.9.1 33473 Q931 Trunk:9 CHAN=1: PrepareTransferTargets Found 1 target
4200757360mS CMTARGET: 9.9.1 33473 Q931 Trunk:9 CHAN=1: GetNoAnswerTimer:15
4200757361mS CMCallEvt: 0.122591.0 -1 BaseEP: NEW CMEndpoint f550794c TOTAL NOW=10 CALL_LIST=4
4200757361mS CMExtnEvt: RAS: CALL LOST (CMCauseTransfer)
4200757361mS CMCallEvt: 0.122589.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
4200757362mS CMExtnEvt: v=1008 State, new=CMESCompleted old=Connected,0,0,RAS
4200757362mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1008 in=0
Called[Confluence Main Route] Type=Voicemail (102) Reason=CMDRX_Attendant Calling[93016703228] Type=Unknown Plan=ISDN Pres=Allowed (0)
BChan: slot=21 chan=9
Cause=126, Transfer(IPO)
4200757362mS CMMap: a=21.9 b=0.0 T0
4200757363mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 122589->0 )
4200757363mS CMCallEvt: 0.122589.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
4200757363mS CMExtnEvt: v=1008 State, new=Idle old=CMESCompleted,0,0,RAS
4200757364mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1008 in=0
BChan: slot=21 chan=9
Cause=126, Transfer(IPO)
4200757364mS CMCallEvt: 0.122591.0 33473 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
4200757365mS CMCallEvt: 0.122590.0 33473 Q931 Trunk:9 CHAN=20: StateChange: END=T CMCSIdle->CMCSOffering
4200757365mS PRN: Setting configured voice gain for ch 20.
4200757365mS CMLineTx: v=9
CMSetup
Line: type=Q931Line 9 Call: lid=0 id=122590 in=0
Called[13018006939] Type=National (2) Reason=CMDRdirect Calling[] Type=National Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=20
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=4065
Timed: 27/03/12 14:11
Locale: enu
4200757366mS CMCallEvt: 0.122589.0 -1 BaseEP: DELETE CMEndpoint f55171dc TOTAL NOW=9 CALL_LIST=4
4200757367mS CMMap: PCG::UnmapBChan pcp[41]b1r0 cp_b f59a4554 other_cp_b f5967aa4
4200757367mS CMMap: a=5.2 b=21.9 M0
4200757367mS CMMap: PCG::UnmapBChan pcp[98]b1r0 cp_b f5967aa4 other_cp_b 0
4200757368mS CD: CALL: 9.9.1 BState=Idle Cut=1 Music=0.0 Aend="Line 9" (5.2) Bend="Joe Tenne(84)" [Line 9] (0.0) CalledNum=84 (Joe Tenne) CallingNum=93016703228 (BENCOMM) Internal=0 Time=6551 AState=Connected
4200757369mS ISDNL3Evt: v=9 stacknum=9 State, new=Initiated, old=NullState id=122590
4200757420mS RES: Tue 27/3/2012 14:11:24 FreeMem=74186476(1) CMMsg=6 (8) Buff=200 964 1000 7414 5 Links=12921
4200757420mS RES2: RTEngine=0, CMRTEngine=0, Timer=62, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
4200757426mS ISDNL3Evt: v=9 stacknum=9 State, new=Proceeding, old=Initiated id=122590
4200757427mS CMLineRx: v=9
CMProceeding
Line: type=Q931Line 9 Call: lid=0 id=122590 in=0
BChan: slot=0 chan=20
4200757427mS CMCallEvt: 0.122591.0 33473 TargetingEP: RequestEnd 0.122590.0 33473 Q931 Trunk:9 CHAN=20
4200757427mS CMTARGET: 9.9.1 33473 Q931 Trunk:9 CHAN=1: CancelTimer CMTCNoAnswerTimeout
4200757428mS CMCallEvt: 0.122591.0 -1 BaseEP: DELETE CMEndpoint f550794c TOTAL NOW=8 CALL_LIST=4
4200757428mS CMCallEvt: 0.122590.0 33473 Q931 Trunk:9 CHAN=20: StateChange: END=B CMCSOffering->CMCSAccept
4200757429mS CD: CALL: 9.9.1 BState=Ringing Cut=3 Music=0.0 Aend="Line 9" (5.2) Bend="Joe Tenne(84)" [Line 9] (5.21) CalledNum=13018006939 (Joe Tenne) CallingNum=93016703228 (BENCOMM) Internal=0 Time=6612 AState=Connected
4200757430mS CMMap: PCG::MapBChan pcp[98]b1r0 cp_b f5967aa4 other_cp_b 0 type CGTypeSimple
4200757430mS CMMap: PCG::MapBChan pcp[117]b1r0 cp_b f59663c8 other_cp_b f5967aa4 type CGTypeSimple
4200757430mS CMMap: a=5.2 b=5.21 M1
4200757430mS CMMap: a=21.7 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 5, total 8
4200757431mS CMMap: a=21.7 b=1.255 T
4200757431mS CMMap: PCG::UnmapBChan pcp[117]b1r0 cp_b f59663c8 other_cp_b f5967aa4
4200757431mS CMMap: a=5.2 b=5.21 M0
4200757431mS CMMap: PCG::MapBChan pcp[117]b1r0 cp_b f59663c8 other_cp_b f59a5cb4 type CGTypeDTMF
4200757431mS CMMap: a=21.7 b=5.21 M2
4200757431mS CMMap: DTMF:layTones g T[243] 4412
4200757432mS CMMap: Started playing tones, cp[117]b1r0 (from g G[242])
4200757432mS CMMap: cp[117]b1r0 SetOperGroup from g G[242] to g G[242] not executed, playing tones in progress 4412
4200757509mS PRN: ConferDSP is alive
4200757607mS CMLineRx: v=9
CMProgress
Line: type=Q931Line 9 Call: lid=0 id=122590 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDNotISDN (1)
Cause=170, Interworking Unspecified(IPO)
4200757607mS CMCallEvt: 0.122590.0 33473 Q931 Trunk:9 CHAN=20: StateChange: END=B CMCSAccept->CMCSRinging
4200757608mS CD: CALL: 9.9.1 BState=Ringing Cut=3 Music=0.0 Aend="Line 9" (5.2) Bend="Joe Tenne(84)" [Line 9] (5.21) CalledNum=13018006939 (Joe Tenne) CallingNum=93016703228 (BENCOMM) Internal=0 Time=6791 AState=Connected
4200757992mS CMMap: PCG::UnmapBChan pcp[117]b1r0 cp_b f59663c8 other_cp_b f59a5cb4
4200757992mS CMMap: a=21.7 b=5.21 M0
4200757992mS CMMap: a=21.7 b=0.0 T0
4200757992mS CMMap: a=21.7 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 4, total 8
4200757993mS CMMap: PCG::MapBChan pcp[117]b1r0 cp_b f59663c8 other_cp_b f5967aa4 type CGTypeSimple
4200757993mS CMMap: a=5.2 b=5.21 M1

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