Buy or Renew
Buy or Renew
Cisco + Splunk: It’s a new day for your data. Learn more.
 
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
cancel
Create a new article
1122
Views
0
Helpful
2
Comments

aa Tcl on cme with 3905

cdnadmin
Level 11
Level 11

on ‎01-25-2014 10:21 AM

This document was generated from CDN thread

Created by: Lisandro Quinteros on 22-11-2012 12:43:24 PM
Hi guys, comming back here again when I am out of solutions.. I'll give you a brief explanation of scenario, I got a 2911 CME with lastest IOS with Ip phones Cisco 3905 and 6921 lastest firmwares available... I install a common auto attendant tcl nearly the same as Tcl programming guide.. (also tried 4 or 5 different tcl that I normally use on each implementation)..Tcl play gretting message, let you dial 3 digits internal number or wait and pass the call to recepcionist The problem I had is, I cant pickup call  comming from PSTN with Cisco 3905 sip phones when the call enter to ej. 100 Directory number the phone rings, but when I press pickup softkey on other Cisco 3905 I heard a busy tone and 100 dn phone continue ringing as if nothing happend..All phones are on the same pickup group... I tried to use conection plar insted of TCL and it works fine..    any idea? thanks..   Edit: forgot to said that I have no problem to pickup the call when I dial from on dn to another and tried to pick up on a third phone.

Subject: RE: aa Tcl on cme with 3905
Replied by: Anusha Kannappan on 23-11-2012 12:08:09 AM
Hi Lisandro,
 
Please share us the logs with the following debugs enabled to further analyze the issue
deb voip app tcl
deb voip app scr
deb voip ccapi inout
 
Thanks,
Anusha

Subject: RE: aa Tcl on cme with 3905
Replied by: Yaw-Ming Chen on 23-11-2012 11:36:41 AM
It's easier to tell what's going on if you can share your scrpt.
Thanks !

Subject: RE: aa Tcl on cme with 3905
Replied by: Lisandro Quinteros on 13-12-2012 10:10:08 AM
Sorry for my late response.. I have been out of office so I couldt make more test.. I test other tcl that I use just for a greetting message on did calls..
This tcl has
handoff appl leg_incoming default "DESTINATION=$dest"
This tcl lt me capture The problem is that this tcl dont have collect digit define...
It there a way to use
handoff appl leg_incoming default "DESTINATION=$dest"
with callect digit ??
 
Ill send you now the tcl that I use that I Initially use with the debugs Anusha ask me
 
With debugs on.. I call from outside to fxo 0/0/0 listen welcome message.. dial 115 heard it ring and try to capture from 150.. when I press capture I heard a bussy tone... disconnect the call from outside, and then hook on adn disconnect ip phone 150 that keeps hearing a bussy tone..
 
 
 
 
 
 

Subject: RE: aa Tcl on cme with 3905
Replied by: Lisandro Quinteros on 13-12-2012 10:12:33 AM
debug voip ccapi inout
 
RTR_SantoTome#
Dec 13 12:15:18.816: //-1/9808F3E39CA3/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=0
   dest=
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=0
   cisco-rdnplan=0
   cisco-rdnpi=0
   cisco-rdnsi=0
   cisco-redirectreason=0   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0
Dec 13 12:15:18.816: //-1/9808F3E39CA3/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x322CF6C8, Call Info(
   Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,
   Incoming Dial-peer=1, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
Dec 13 12:15:18.816: //-1/9808F3E39CA3/CCAPI/ccCheckClipClir:
   In: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:18.816: //-1/9808F3E39CA3/CCAPI/ccCheckClipClir:
   Out: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:18.816: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:18.816: :cc_get_feature_vsa malloc success
Dec 13 12:15:18.816: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:18.816:  cc_get_feature_vsa count is 1
Dec 13 12:15:18.816: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:18.816: :FEATURE_VSA attributes are: feature_name:0,feature_time:745202184,feature_id:6346
Dec 13 12:15:18.816: //14772/9808F3E39CA3/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=(TON=Unknown, NPI=Unknown))
Dec 13 12:15:18.816: //14772/9808F3E39CA3/CCAPI/cc_process_call_setup_ind:
   Event=0x320DB178
Dec 13 12:15:18.820: //14772/9808F3E39CA3/CCAPI/ccCallSetContext:
   Context=0x341DBAA4
Dec 13 12:15:18.820: //14772/9808F3E39CA3/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 14772 with tag 1 to app "_ManagedAppProcess_preatendedor"
Dec 13 12:15:18.820: //14772/9808F3E39CA3/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/ccDisassociateStream:
   Record Context=0x0, Stream Call Id=14773, Call Id=14772
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/ccDisassociateStream:
   Call Entry(Stream Status=1, Digit Enable=FALSE)
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/ccAssociateStream:
   Coder=5, DTMF Relay=1, Vad=0,
   Record Function=0x0, Event Queue=0x31C8E4AC, Stream Context=30FB0708,
   Record Context=0x0, Stream Call Id=14773, Call Id=14772
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/ccAssociateStream:
   Call Entry(Stream Status=2, Digit Enable=FALSE)
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/ccSetDigitTimeouts:
   Initial Digit Timeout=3000(ms), Inter Digit Timeout=4000(ms)
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/ccSetDigitTimeouts:
   Call Entry(Inter Digit Timeout=4000(ms), Initial Digit Timeout=3000(ms))
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/ccCallReportDigits:
   (callID=0x39B4, digit_event=0x1, enable=TRUE, consume=FALSE)
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=14772
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x322CF6C8, callID=0x39B4, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x322CF6C8, Call Id=14772
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=3000(ms), Inter Digit Timeout=4000(ms))
Dec 13 12:15:18.872: //14772/9808F3E39CA3/CCAPI/cc_api_call_associated:
   Interface=0x322CF6C8, CallId=14772, Disposition=0
   Play Function=0x25B5F85C, Codec=0x5, Vad=0x0
   Media Type=0x6, SPI Context=0x341A3A3C, Stream CallId=14773
   TX Dynamic Pt=0x0, RX Dynamic Pt=0x0
Dec 13 12:15:20.432: //14772/9808F3E39CA3/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14772, Digit=1, DigitBeginFlags=0x1,
   Rtp Timestamp=0x208226C0, Rtp Expiration=0x0
Dec 13 12:15:20.580: //14772/9808F3E39CA3/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14772, Digit=1, Duration=185,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:20.580: //14772/9808F3E39CA3/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:20.580: //14772/9808F3E39CA3/CCAPI/ccDisassociateStream:
   Record Context=0x0, Stream Call Id=14773, Call Id=14772
Dec 13 12:15:20.580: //14772/9808F3E39CA3/CCAPI/ccDisassociateStream:
   Call Entry(Stream Status=1, Digit Enable=TRUE)
Dec 13 12:15:20.772: //14772/9808F3E39CA3/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14772, Digit=1, DigitBeginFlags=0x1,
   Rtp Timestamp=0x20823160, Rtp Expiration=0x0
Dec 13 12:15:20.920: //14772/9808F3E39CA3/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14772, Digit=1, Duration=185,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:20.920: //14772/9808F3E39CA3/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:21.244: //14772/9808F3E39CA3/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14772, Digit=5, DigitBeginFlags=0x1,
   Rtp Timestamp=0x20824010, Rtp Expiration=0x0
Dec 13 12:15:21.444: //14772/9808F3E39CA3/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14772, Digit=5, Duration=235,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:21.444: //14772/9808F3E39CA3/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:21.532: //-1/99A6C6309CA7/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=111
   ----- ccCallInfo IE subfields -----
   cisco-ani=111
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=0
   dest=0
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=0
   cisco-rdnplan=0
   cisco-rdnpi=-1
   cisco-rdnsi=-1
   cisco-redirectreason=-1   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0
Dec 13 12:15:21.532: //-1/99A6C6309CA7/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x31C6D784, Call Info(
   Calling Number=111,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=0(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=FALSE,
   Incoming Dial-peer=40003, Progress Indication=NULL(0), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=14775
Dec 13 12:15:21.532: //-1/99A6C6309CA7/CCAPI/ccCheckClipClir:
   In: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:21.532: //-1/99A6C6309CA7/CCAPI/ccCheckClipClir:
   Out: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:21.532: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:21.532: :cc_get_feature_vsa malloc success
Dec 13 12:15:21.532: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:21.532:  cc_get_feature_vsa count is 2
Dec 13 12:15:21.532: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:21.532: :FEATURE_VSA attributes are: feature_name:0,feature_time:745201736,feature_id:6347
Dec 13 12:15:21.532: //14775/99A6C6309CA7/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=0(TON=Unknown, NPI=Unknown))
Dec 13 12:15:21.532: //14775/99A6C6309CA7/CCAPI/cc_process_call_setup_ind:
   Event=0x320DB178
Dec 13 12:15:21.532: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 0
Dec 13 12:15:21.532: //14775/99A6C6309CA7/CCAPI/ccCallSetContext:
   Context=0x341D1724
Dec 13 12:15:21.532: //14775/99A6C6309CA7/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 14775 with tag 40003 to app "_ManagedAppProcess_Default"
Dec 13 12:15:21.536: //14775/99A6C6309CA7/CCAPI/ccCallSetupAck:
   Call Id=14775
Dec 13 12:15:21.536: //14775/99A6C6309CA7/CCAPI/cc_api_set_transfer_info:
   Transfer Number=, Transfer Reason=0x0
Dec 13 12:15:21.536: //14775/99A6C6309CA7/CCAPI/ccSetDigitTimeouts:
   Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)
Dec 13 12:15:21.536: //14775/99A6C6309CA7/CCAPI/ccSetDigitTimeouts:
   Call Entry(Inter Digit Timeout=4000(ms), Initial Digit Timeout=4000(ms))
Dec 13 12:15:21.536: //14775/99A6C6309CA7/CCAPI/ccCallReportDigits:
   (callID=0x39B7, digit_event=0x1, enable=TRUE, consume=FALSE)
Dec 13 12:15:21.536: //14775/99A6C6309CA7/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=14775
Dec 13 12:15:21.628: //14775/99A6C6309CA7/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x31C6D784, callID=0x39B7, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
Dec 13 12:15:21.628: //14775/99A6C6309CA7/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x31C6D784, Call Id=14775
Dec 13 12:15:21.628: //14775/99A6C6309CA7/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms))
Dec 13 12:15:22.028: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14775, Digit=0, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:22.028: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14775, Digit=0, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:22.028: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:22.460: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14775, Digit=3, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:22.460: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14775, Digit=3, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:22.460: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:22.808: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14775, Digit=4, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:22.808: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14775, Digit=4, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:22.808: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:23.080: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14775, Digit=1, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:23.080: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, DestinatiMF
Dec 13 12:15:23.080: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)on Call Id=-1,
   Source Call Id=14775, Digit=1, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DT
Dec 13 12:15:23.324: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14775, Digit=4, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:23.324: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14775, Digit=4, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:23.324: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:23.740: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, MF
Dec 13 12:15:23.740: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:23.744: //14775/99A6C6309CA7/CCAPI/ccCallReportDigits:
   Destination Call Id=-1,
   Source Call Id=14775, Digit=9, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 1(callID=0x39B7, digit_event=0x0, enable=FALSE, consume=FALSE)
Dec 13 12:15:23.744: //14775/99A6C6309CA7/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=14775
Dec 13 12:15:23.744: //14775/99A6C6309CA7/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x31C6D784, callID=0x39B7, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
Dec 13 12:15:23.744: //14775/99A6C6309CA7/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x31C6D784, Call Id=14775
Dec 13 12:15:23.744: //14775/99A6C6309CA7/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms))3 12:15:23.740: //14775/99A6C6309CA7/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14775, Digit=9, Duration=250,
_CALL_HISTORY: CallLegType 2, ConnectionId 99A6C630445511E29CA7A4245B31D7B, SetupTime 12:15:21.536 UTC Thu Dec 13 2012, PeerAddress 111, PeerSubAddress , DisconnectCause 1C  , DisconnectText invalid number (28), ConnectTime 12:15:23.836 UTC Thu Dec 13 2012, DisconnectTime 12:15:23.836 UTC Thu Dec 13 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
                     Dec 13 12:15:23.744: //14775/99A6C6309CA7/CCAPI/ccCallDisconnect:
   Cause Value=28, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Dec 13 12:15:23.744: //14775/99A6C6309CA7/CCAPI/ccCallDisconnect:
   Cause Value=28, Call Entry(Responsed=TRUE, Cause Value=28)
Dec 13 12:15:23: %VOIPAAA-5-VOIP
Dec 13 12:15:23.836: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:23.836: :Inside cc_build_feature_vsa
Dec 13 12:15:23.836: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:23.836:  feature call basic
Dec 13 12:15:23.836: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:23.836: cc_build_feature_vsa attr is fn:TWC,ft:12/13/2012 12:15:21.532,cgn:111,cdn:0,frs:0,fid:6347,fcid:99A6C630445511E29CA7A4245B31D7B,legID:39B7
Dec 13 12:15:23: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 12:15:21.532,cgn:111,cdn:0,frs:0,fid:6347,fcid:99A6C630445511E29CA7A4245B31D7B,legID:39B7,bguid:99A6C630445511E29CA7A42405B31D7B
Dec 13 12:15:23.836: //-1/xxxxxxxxxxxx/CCAPI/cc_build_csv_feature_vsa:
  
Dec 13 12:15:23.836: :Inside cc_build_feature_vsa
Dec 13 12:15:23.836: //-1/xxxxxxxxxxxx/CCAPI/cc_build_csv_feature_vsa:
  
Dec 13 12:15:23.836:  feature call basic
Dec 13 12:15:23.836: //-1/xxxxxxxxxxxx/CCAPI/cc_build_csv_feature_vsa:
  
Dec 13 12:15:23.836: cc_build_feature_vsa attr is "TWC","12/13/2012 12:15:21.532","111","0",0,6347,99A6C630 445511E2 9CA7A424 5B31D7B,39B7,"","","",""
Dec 13 12:15:23.836: //14775/99A6C6309CA7/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x31C6D784, Tag=0x0, Call Id=14775,
   Call Entry(Disconnect Cause=28, Voice Class Cause Code=0, Retry Count=0)
Dec 13 12:15:23.836: //14775/99A6C6309CA7/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Dec 13 12:15:23.836: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Dec 13 12:15:23.836: :cc_free_feature_vsa freeing 2C6AE040
Dec 13 12:15:23.836: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Dec 13 12:15:23.836:  vsacount in free is 1
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/cc_handle_inter_digit_timer:
   Generate inter-digit timeout CC_EV_CALL_DIGIT_END event
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/cc_handle_inter_digit_timer:
   Generate inter-digit timeout CC_EV_CALL_DIGIT_END event
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/ccCallReportDigits:
   (callID=0x39B4, digit_event=0x0, enable=FALSE, consume=FALSE)
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=14772
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x322CF6C8, callID=0x39B4, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x322CF6C8, Call Id=14772
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=3000(ms), Inter Digit Timeout=4000(ms))
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=FALSE, Mode=0,
   Outgoing Dial-peer=40014, Params=0x341C8864, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/ccCheckClipClir:
   In: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/ccCheckClipClir:
   Out: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/ccCallSetupRequest:
   Destination Pattern=115$, Called Number=115, Digit Strip=FALSE
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/ccCallSetupRequest:
   Calling Number=(TON=Unknowunn, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=115(TON=Unknown, NPI=Unknown),
   Redirect Number=, Display Info=
   Account Number=, Final Destination Flag=TRUE,
   Guid=9808F3E3-4455-11E2-9CA3-A42405B31D7B, Outgoing Dial-peer=40014
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cidd allsco-anisi=0
   dest=115
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=0
   cisco-rdnplan=0
   cisco-rdnpi=0
   cisco-rdnsi=0
   cisco-redirectreason=0   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0
Dec 13 12:15:25.444: //14772/9808F3E39CA3/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x31C6D784, Interface Type=3, Destination=, Mode=0x0,
   Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screeni
                 ^
% Invalid input detected at '^' marker.
RTR_SantoTome#ng=Not Screened, Presentation=Allowed),
   Called Number=115(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=40014, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
Dec 13 12:15:25.444: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:25.444: :cc_get_feature_vsa malloc success
Dec 13 12:15:25.444: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:25.444:  cc_get_feature_vsa count is 2
Dec 13 12:15:25.444: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:25.444: :FEATURE_VSA attributes are: feature_name:0,feature_time:745201736,feature_id:6348
Dec 13 12:15:25.444: //14776/9808F3E39CA3/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
Dec 13 12:15:25.444: //14776/9808F3E39CA3/CCAPI/ccCallSetContext:
   Context=0x341C8814
Dec 13 12:15:25.444: //14772/980und a8F3E39CA3/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=40014
Dec 13 12:15:25.448: //14776/9808F3E39CA3/CCAPI/cc_api_call_proceeding:
   Interface=0x31C6D784, Progress Indication=NULL(0)
Dec 13 12:15:25.664: //14776/9808F3E39CA3/CCAPI/cc_api_call_alert:
   Interface=0x31C6D784, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
Dec 13 12:15:25.664: //14776/9808F3E39CA3/CCAPI/cc_api_call_alert:
   Call Entry(Retry Count=0, Responsed=TRUE)
Dec 13 12:15:25.664: //14772/9808F3E39CA3/CCAll
All possible debugging has been turned off
RTR_SantoTome#PI/ccCallAlert:
   Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
Dec 13 12:15:25.664: //14772/9808F3E39CA3/CCAPI/ccCallAlert:
   Call Entry(Responsed=TRUE, Alert Sent=TRUE)
Dec 13 12:15:29.192: //-1/9E3799C49CAF/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=111
   ----- ccCallInfo IE subfields -----
   cisco-ani=111
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=0
   dest=0
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=0
   cisco-rdnplan=0
   cisco-rdnpi=-1
   cisco-rdnsi=-1
   cisco-redirectreason=-1   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0
Dec 13 12:15:29.196: //-1/9E3799C49CAF/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x31C6D784, Call Info(
   Calling Number=111,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=0(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=FALSE,
   Incoming Dial-peer=40003, Progress Indication=NULL(0), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=14777
Dec 13 12:15:29.196: //-1/9E3799C49CAF/CCAPI/ccCheckClipClir:
   In: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:29.196: //-1/9E3799C49CAF/CCAPI/ccCheckClipClir:
   Out: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:29.196: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:29.196: :cc_get_feature_vsa malloc success
Dec 13 12:15:29.196: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:29.196:  cc_get_feature_vsa count is 3
Dec 13 12:15:29.196: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:29.196: :FEATURE_VSA attributes are: feature_name:0,feature_time:745199496,feature_id:6349
Dec 13 12:15:29.196: //14777/9E3799C49CAF/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=0(TON=Unknown, NPI=Unknown))
Dec 13 12:15:29.196: //14777/9E3799C49CAF/CCAPI/cc_process_call_setup_ind:
   Event=0x320DB178
Dec 13 12:15:29.196: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 0
Dec 13 12:15:29.196: //14777/9E3799C49CAF/CCAPI/ccCallSetContext:
   Context=0x341DA634
Dec 13 12:15:29.196: //14777/9E3799C49CAF/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 14777 with tag 40003 to app "_ManagedAppProcess_Default"
Dec 13 12:15:29.196: //14777/9E3799C49CAF/CCAPI/ccCallSetupAck:
   Call Id=14777
Dec 13 12:15:29.196: //14777/9E3799C49CAF/CCAPI/cc_api_set_transfer_info:
   Transfer Number=, Transfer Reason=0x0
Dec 13 12:15:29.196: //14777/9E3799C49CAF/CCAPI/ccSetDigitTimeouts:
   Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)
Dec 13 12:15:29.196: //14777/9E3799C49CAF/CCAPI/ccSetDigitTimeouts:
   Call Entry(Inter Digit Timeout=4000(ms), Initial Digit Timeout=4000(ms))
Dec 13 12:15:29.196: //14777/9E3799C49CAF/CCAPI/ccCallReportDigits:
   (callID=0x39B9, digit_event=0x1, enable=TRUE, consume=FALSE)
Dec 13 12:15:29.196: //14777/9E3799C49CAF/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=14777
Dec 13 12:15:29.272: //14777/9E3799C49CAF/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x31C6D784, callID=0x39B9, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
Dec 13 12:15:29.272: //14777/9E3799C49CAF/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x31C6D784, Call Id=14777
Dec 13 12:15:29.272: //14777/9E3799C49CAF/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms))
Dec 13 12:15:29.588: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=0, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:29.588: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=0, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:29.588: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:29.940: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=3, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:29.940: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=3, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:29.940: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:30.288: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=4, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:30.288: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=4, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:30.292: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:30.556: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=1, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:30.556: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=1, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:30.556: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:30.912: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=4, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:30.912: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=4, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:30.912: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:31.432: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=9, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:31.432: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14777, Digit=9, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:31.432: //14777/9E3799C49CAF/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:31.432: //14777/9E3799C49CAF/CCAPI/ccCallReportDigits:
   (callID=0x39B9, digit_event=0x0, enable=FALSE, consume=FALSE)
Dec 13 12:15:31.432: //14777/9E3799C49CAF/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=14777
Dec 13 12:15:31.432: //14777/9E3799C49CAF/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x31C6D784, callID=0x39B9, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
Dec 13 12:15:31.432: //14777/9E3799C49CAF/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x31C6D784, Call Id=14777
Dec 13 12:15:31.432: //14777/9E3799C49CAF/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms))
Dec 13 12:15:31.432: //14777/9E3799C49CAF/CCAPI/ccCallDisconnect:
   Cause Value=28, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Dec 13 12:15:31.432: //14777/9E3799C49CAF/CCAPI/ccCallDisconnect:
   Cause Value=28, Call Entry(Responsed=TRUE, Cause Value=28)
Dec 13 12:15:31: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2, ConnectionId 9E3799C4445511E29CAFA4245B31D7B, SetupTime 12:15:29.194 UTC Thu Dec 13 2012, PeerAddress 111, PeerSubAddress , DisconnectCause 1C  , DisconnectText invalid number (28), ConnectTime 12:15:31.524 UTC Thu Dec 13 2012, DisconnectTime 12:15:31.524 UTC Thu Dec 13 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
Dec 13 12:15:31.524: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:31.524: :Inside cc_build_feature_vsa
Dec 13 12:15:31.524: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:31.524:  feature call basic
Dec 13 12:15:31.524: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:31.524: cc_build_feature_vsa attr is fn:TWC,ft:12/13/2012 12:15:29.196,cgn:111,cdn:0,frs:0,fid:6349,fcid:9E3799C4445511E29CAFA4245B31D7B,legID:39B9
Dec 13 12:15:31: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 12:15:29.196,cgn:111,cdn:0,frs:0,fid:6349,fcid:9E3799C4445511E29CAFA4245B31D7B,legID:39B9,bguid:9E3799C4445511E29CAFA42405B31D7B
Dec 13 12:15:31.524: //-1/xxxxxxxxxxxx/CCAPI/cc_build_csv_feature_vsa:
  
Dec 13 12:15:31.524: :Inside cc_build_feature_vsa
Dec 13 12:15:31.524: //-1/xxxxxxxxxxxx/CCAPI/cc_build_csv_feature_vsa:
  
Dec 13 12:15:31.524:  feature call basic
Dec 13 12:15:31.524: //-1/xxxxxxxxxxxx/CCAPI/cc_build_csv_feature_vsa:
  
Dec 13 12:15:31.524: cc_build_feature_vsa attr is "TWC","12/13/2012 12:15:29.196","111","0",0,6349,9E3799C4 445511E2 9CAFA424 5B31D7B,39B9,"","","",""
Dec 13 12:15:31.524: //14777/9E3799C49CAF/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x31C6D784, Tag=0x0, Call Id=14777,
   Call Entry(Disconnect Cause=28, Voice Class Cause Code=0, Retry Count=0)
Dec 13 12:15:31.528: //14777/9E3799C49CAF/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Dec 13 12:15:31.528: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Dec 13 12:15:31.528: :cc_free_feature_vsa freeing 2C6AD780
Dec 13 12:15:31.528: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Dec 13 12:15:31.528:  vsacount in free is 2
Dec 13 12:15:36.268: //-1/A26F50059CB5/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=150
   ----- ccCallInfo IE subfields -----
   cisco-ani=150
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=0
   dest=3
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=0
   cisco-rdnplan=0
   cisco-rdnpi=-1
   cisco-rdnsi=-1
   cisco-redirectreason=-1   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0
Dec 13 12:15:36.268: //-1/A26F50059CB5/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x31C6D784, Call Info(
   Calling Number=150,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=3(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=FALSE,
   Incoming Dial-peer=40011, Progress Indication=NULL(0), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=14778
Dec 13 12:15:36.268: //-1/A26F50059CB5/CCAPI/ccCheckClipClir:
   In: Calling Number=150(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:36.268: //-1/A26F50059CB5/CCAPI/ccCheckClipClir:
   Out: Calling Number=150(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:36.272: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:36.272: :cc_get_feature_vsa malloc success
Dec 13 12:15:36.272: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:36.272:  cc_get_feature_vsa count is 3
Dec 13 12:15:36.272: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:36.272: :FEATURE_VSA attributes are: feature_name:0,feature_time:745199496,feature_id:6350
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=150(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=3(TON=Unknown, NPI=Unknown))
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/cc_process_call_setup_ind:
   Event=0x320DB178
Dec 13 12:15:36.272: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 3
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/ccCallSetContext:
   Context=0x341CC564
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 14778 with tag 40011 to app "_ManagedAppProcess_Default"
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/ccCallSetupAck:
   Call Id=14778
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/cc_api_set_transfer_info:
   Transfer Number=, Transfer Reason=0x0
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/ccCallReportDigits:
   (callID=0x39BA, digit_event=0x0, enable=FALSE, consume=FALSE)
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=14778
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x31C6D784, callID=0x39BA, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x31C6D784, Call Id=14778
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms))
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/ccCallDisconnect:
   Cause Value=28, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Dec 13 12:15:36.272: //14778/A26F50059CB5/CCAPI/ccCallDisconnect:
   Cause Value=28, Call Entry(Responsed=TRUE, Cause Value=28)
Dec 13 12:15:36: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2, ConnectionId A26F5005445511E29CB5A4245B31D7B, SetupTime 12:15:36.278 UTC Thu Dec 13 2012, PeerAddress 150, PeerSubAddress , DisconnectCause 1C  , DisconnectText invalid number (28), ConnectTime 12:15:36.288 UTC Thu Dec 13 2012, DisconnectTime 12:15:36.288 UTC Thu Dec 13 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
Dec 13 12:15:36.288: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:36.288: :Inside cc_build_feature_vsa
Dec 13 12:15:36.288: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:36.288:  feature call basic
Dec 13 12:15:36.288: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:36.288: cc_build_feature_vsa attr is fn:TWC,ft:12/13/2012 12:15:36.272,cgn:150,cdn:3,frs:0,fid:6350,fcid:A26F5005445511E29CB5A4245B31D7B,legID:39BADisposition=0, Interface=0x31C6D784, Tag=0x0, Call Id=14778,
   Call Entry(Disconnect Cause=28, Voice Class Cause Code=0, Retry Count=0)
Dec 13 12:15:36.288: //14778/A26F50059CB5/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Dec 13 12:15:36.288: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Dec 13 12:15:36.288: :cc_free_feature_vsa freeing 2C6AD780
Dec 13 12:15:36.288: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Dec 13 12:15:36.288:  vsacount in free is 2
Dec 13 12:15:42.568: //-1/A6309CB09CBA/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=111
   ----- ccCallInfo IE subfields -----
   cisco-ani=111
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=0
   dest=0
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=0
   cisco-rdnplan=0
   cisco-rdnpi=-1
   cisco-rdnsi=-1
   cisco-redirectreason=-1   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0
Dec 13 12:15:42.568: //-1/A6309CB09CBA/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x31C6D784, Call Info(
   Calling Number=111,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=0(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=FALSE,
   Incoming Dial-peer=40003, Progress Indication=NULL(0), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=14779
Dec 13 12:15:42.568: //-1/A6309CB09CBA/CCAPI/ccCheckClipClir:
   In: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:42.568: //-1/A6309CB09CBA/CCAPI/ccCheckClipClir:
   Out: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Dec 13 12:15:42.568: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:42.568: :cc_get_feature_vsa malloc success
Dec 13 12:15:42.568: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:42.568:  cc_get_feature_vsa count is 3
Dec 13 12:15:42.568: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Dec 13 12:15:42.568: :FEATURE_VSA attributes are: feature_name:0,feature_time:745199496,feature_id:6351
Dec 13 12:15:42.568: //14779/A6309CB09CBA/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=0(TON=Unknown, NPI=Unknown))
Dec 13 12:15:42.568: //14779/A6309CB09CBA/CCAPI/cc_process_call_setup_ind:
   Event=0x320DB178
Dec 13 12:15:42.568: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 0
Dec 13 12:15:42.568: //14779/A6309CB09CBA/CCAPI/ccCallSetContext:
   Context=0x341CD9D4
Dec 13 12:15:42.568: //14779/A6309CB09CBA/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 14779 with tag 40003 to app "_ManagedAppProcess_Default"Enabled=TRUE, Call Id=14779
Dec 13 12:15:43.104: //14779/A6309CB09CBA/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x31C6D784, callID=0x39BB, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
Dec 13 12:15:43.104: //14779/A6309CB09CBA/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x31C6D784, Call Id=14779
Dec 13 12:15:43.104: //14779/A6309CB09CBA/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms))
Dec 13 12:15:43.108: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14779, Digit=0, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:43.108: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14779, Digit=0, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:43.108: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:43.668: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14779, Digit=3, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:43.668: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14779, Digit=3, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:43.668: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:44.656: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14779, Digit=4, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:44.656: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14779, Digit=4, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:44.656: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:45.008: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14779, Digit=1, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:45.008: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14779, Digit=1, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:45.008: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:45.896: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14779, Digit=4, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
Dec 13 12:15:45.896: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=14779, Digit=4, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Dec 13 12:15:45.896: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
Dec 13 12:15:45.996: //14779/A6309CB09CBA/CCAPI/cc_api_call_digit_begin:
   Cause Value=28, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Dec 13 12:15:45.996: //14779/A6309CB09CBA/CCAPI/ccCallDisconnect:
   Cause Value=28, Call Entry(Responsed=TRUE, Cause Value=28)
Dec 13 12:15:50.444: //14772/9808F3E39CA3/CCAPI/cc_api_call_disconnected:
   Cause Value=86, Interface=0x322CF6C8, Call Id=14772
Dec 13 12:15:50.444: //14772/9808F3E39CA3/CCAPI/cc_api_call_disconnected:
   Call Entry(Responsed=TRUE, Cause Value=86, Retry Count=0)
Dec 13 12:15:50.444: //14772/9808F3E39CA3/CCAPI/ccGenerateToneInfo:
   Stop Tone On Digit=FALSE, Tone=Null,
   Tone Direction=Network, Params=0x0, Call Id=14772
Dec 13 12:15:50.444: //14776/9808F3E39CA3/CCAPI/ccCallDisconnect:
   Cause Value=86, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Dec 13 12:15:50.444: //14776/9808F3E39CA3/CCAPI/ccCallDisconnect:
   Cause Value=86, Call Entry(Responsed=TRUE, Cause Value=86)
Dec 13 12:15:50.444: //14772/9808F3E39CA3/CCAPI/ccCallDisconnect:
   Cause Value=86, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=86)
Dec 13 12:15:50.444: //14772/9808F3E39CA3/CCAPI/ccCallDisconnect:
   Cause Value=86, Call Entry(Responsed=TRUE, Cause Value=86)
Dec 13 12:15:50.444: //14772/9808F3E39CA3/CCAPI/cc_api_get_transfer_info:
   Transfer Number=NULL
Dec 13 12:15:50: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2, ConnectionId 9808F3E3445511E29CA3A4245B31D7B, SetupTime 12:15:25.446 UTC Thu Dec 13 2012, PeerAddress 115, PeerSubAddress , DisconnectCause 56  , DisconnectText call cleared (86), ConnectTime 12:15:50.456 UTC Thu Dec 13 2012, DisconnectTime 12:15:50.456 UTC Thu Dec 13 2012, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
Dec 13 12:15:50.460: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:50.460: :Inside cc_build_feature_vsa
Dec 13 12:15:50.460: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:50.460:  feature call basic
Dec 13 12:15:50.460: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
  
Dec 13 12:15:50.460: cc_build_feature_vsa attr is fn:TWC,ft:12/13/2012 12:15:25.444,cgn:,cdn:115,frs:0,fid:6348,fcid:9808F3E3445511E29CA3A4245B31D7B,legID:39B8
Dec 13 12:15:50.464: //14772/9808F3E39CA3/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Dec 13 12:15:50.464: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Dec 13 12:15:50.464: :cc_free_feature_vsa freeing 2C6AE200
Dec 13 12:15:50.464: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Dec 13 12:15:50.464:  vsacount in free is 0
 
 

Subject: RE: aa Tcl on cme with 3905
Replied by: Lisandro Quinteros on 13-12-2012 10:19:19 AM
DEBUG VOIP APP TCL
 
 

Dec 13 12:17:51.076: //14782//TCL :/tcl_InfotagObjCmd:  infotag get leg_isdid
Dec 13 12:17:51.076: //14782//TCL :/tcl_InfotagGetObjCmd: infotag get leg_isdid
Dec 13 12:17:51.076: //14782//AFW_:/vtr_lg_incdid: argc 2 argindex 2
Dec 13 12:17:51.076: //14782//TCL :/tcl_InfotagObjCmd:  infotag get leg_dnis
Dec 13 12:17:51.076: //14782//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Dec 13 12:17:51.076: //14782//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Dec 13 12:17:51.076: //14782//TCL :/tcl_LegObjCmd:  leg proceeding leg_incoming
Dec 13 12:17:51.076: //14782//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
Dec 13 12:17:51.076: //14782//AFW_:/vtd_lg_incoming: argc 2
Dec 13 12:17:51.076: //14782//AFW_:/vtd_lg_incoming: Legs [14782 ]
Dec 13 12:17:51.076: //14782//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:17:51.076: //14782//TCL :/tcl_LegObjCmd:  leg setup  callInfo leg_incoming
Dec 13 12:17:51.076: //14782//AFW_:/vtd_lg_incoming: argc 4
Dec 13 12:17:51.076: //14782//AFW_:/vtd_lg_incoming: Legs [14782 ]
Dec 13 12:17:51.076: //14782//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:17:51.076: //14782//TCL :/tcl_FSMObjCmd:  fsm setstate PLACECALL
Dec 13 12:17:51.080: //14782//TCL :/tcl_FSMSetStateObjCmd: setstate setstate PLACECALL
Dec 13 12:17:51.080: //14782//TCL :/tcl_InfotagObjCmd:  infotag get evt_status
Dec 13 12:17:51.080: //14782//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Dec 13 12:17:51.080: //14782//AFW_:/vtr_ev_status: argc 2 argindex 2
Dec 13 12:17:51.080: //14782//PACK:/tcl_MediaObjCmd:  media play leg_incoming flash:sp_dest_unreachable.au
Dec 13 12:17:51.080: //14782//PACK:/tcl_MediaPlayObjCmd:  play leg_incoming flash:sp_dest_unreachable.au
Dec 13 12:17:51.080: //14782//AFW_:/vtd_lg_incoming: argc 3
Dec 13 12:17:51.080: //14782//AFW_:/vtd_lg_incoming: Legs [14782 ]
Dec 13 12:17:51.080: //14782//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:17:51.080: //14782//PACK:/Media_Play_Start: 
Dec 13 12:17:51.080: //14782//TCL :/tcl_FSMObjCmd:  fsm setstate ROBERTO
Dec 13 12:17:51.080: //14782//TCL :/tcl_FSMSetStateObjCmd: setstate setstate ROBERTO
Dec 13 12:17:51.132: //14782//TCL :/tcl_InfotagObjCmd:  infotag get evt_legs
Dec 13 12:17:51.132: //14782//TCL :/tcl_InfotagGetObjCmd: infotag get evt_legs
Dec 13 12:17:51.132: //14782//AFW_:/vtr_ev_legs: argc 2
Dec 13 12:17:51.132: //14782//AFW_:/vtr_ev_legs: EVCALLID [14782]
Dec 13 12:17:51.132: //14782//TCL :/tcl_InfotagObjCmd:  infotag get evt_status
Dec 13 12:17:51.132: //14782//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Dec 13 12:17:51.132: //14782//AFW_:/vtr_ev_status: argc 2 argindex 2
Dec 13 12:17:51.132: //14782//PACK:/tcl_MediaObjCmd:  media play leg_incoming flash:mensaje.au
Dec 13 12:17:51.132: //14782//PACK:/tcl_MediaPlayObjCmd:  play leg_incoming flash:mensaje.au
Dec 13 12:17:51.132: //14782//AFW_:/vtd_lg_incoming: argc 3
Dec 13 12:17:51.132: //14782//AFW_:/vtd_lg_incoming: Legs [14782 ]
Dec 13 12:17:51.132: //14782//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:17:51.132: //14782//PACK:/Media_Play_Start: 
Dec 13 12:17:51.132: //14782//TCL :/tcl_LegObjCmd:  leg collectdigits leg_incoming param
Dec 13 12:17:51.132: //14782//AFW_:/vtd_lg_incoming: argc 3
Dec 13 12:17:51.132: //14782//AFW_:/vtd_lg_incoming: Legs [14782 ]
Dec 13 12:17:51.132: //14782//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:18:04.844: //14782//TCL :/tcl_InfotagObjCmd:  infotag get evt_status
Dec 13 12:18:04.844: //14782//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Dec 13 12:18:04.844: //14782//AFW_:/vtr_ev_status: argc 2 argindex 2
Dec 13 12:18:04.844: //14782//TCL :/tcl_InfotagObjCmd:  infotag get evt_legs
Dec 13 12:18:04.844: //14782//TCL :/tcl_InfotagGetObjCmd: infotag get evt_legs
Dec 13 12:18:04.844: //14782//AFW_:/vtr_ev_legs: argc 2
Dec 13 12:18:04.844: //14782//AFW_:/vtr_ev_legs: EVCALLID [14782]
Dec 13 12:18:04.844: //14782//TCL :/tcl_InfotagObjCmd:  infotag get evt_dcdigits
Dec 13 12:18:04.844: //14782//TCL :/tcl_InfotagGetObjCmd: infotag get evt_dcdigits
Dec 13 12:18:04.844: //14782//AFW_:/vtr_ev_dcdigits: argc 2
Dec 13 12:18:04.844: //14782//AFW_:/vtr_ev_dcdigits: DCDIGITS [115]
Dec 13 12:18:04.844: //14782//TCL :/tcl_InfotagObjCmd:  infotag get evt_legs
Dec 13 12:18:04.844: //14782//TCL :/tcl_InfotagGetObjCmd: infotag get evt_legs
Dec 13 12:18:04.844: //14782//AFW_:/vtr_ev_legs: argc 2
Dec 13 12:18:04.844: //14782//AFW_:/vtr_ev_legs: EVCALLID [14782]
Dec 13 12:18:04.844: //14782//TCL :/tcl_LegObjCmd:  leg setup 115 callInfo leg_incoming
Dec 13 12:18:04.844: //14782//AFW_:/vtd_lg_incoming: argc 4
Dec 13 12:18:04.844: //14782//AFW_:/vtd_lg_incoming: Legs [14782 ]
Dec 13 12:18:04.844: //14782//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:18:14: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2, ConnectionId F8F744445611E29CCFA4245B31D7B, SetupTime 12:18:14.872 UTC Thu Dec 13 2012, PeerAddress 150, PeerSubAddress , DisconnectCause 1C  , DisconnectText invalid number (28), ConnectTime 12:18:14.892 UTC Thu Dec 13 2012, DisconnectTime 12:18:14.892 UTC Thu Dec 13 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
Dec 13 12:18:14: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 12:18:14.876,cgn:150,cdn:3,frs:0,fid:6355,fcid:F8F744445611E29CCFA4245B31D7B,legID:39C4,bguid:00F8F744445611E29CCFA42405B31D7B
Dec 13 12:18:16: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2, ConnectionId A3857E445611E29CC9A4245B31D7B, SetupTime 12:18:14.312 UTC Thu Dec 13 2012, PeerAddress 108, PeerSubAddress , DisconnectCause 1C  , DisconnectText invalid number (28), ConnectTime 12:18:16.812 UTC Thu Dec 13 2012, DisconnectTime 12:18:16.812 UTC Thu Dec 13 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
Dec 13 12:18:16: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 12:18:14.316,cgn:108,cdn:0,frs:0,fid:6354,fcid:A3857E445611E29CC9A4245B31D7B,legID:39C3,bguid:00A3857E445611E29CC9A42405B31D7B
Dec 13 12:18:24.716: //14782//TCL :/tcl_InfotagObjCmd:  infotag get evt_status
Dec 13 12:18:24.716: //14782//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Dec 13 12:18:24.716: //14782//AFW_:/vtr_ev_status: argc 2 argindex 2
Dec 13 12:18:25.376: //14782//TCL :/tcl_CallObjCmd:  call close
Dec 13 12:18:25.376: //14782//TCL :/tcl_CallCloseObjCmd:  close
Dec 13 12:18:25: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2, ConnectionId F2C9FD6B445511E29CC2A4245B31D7B, SetupTime 12:18:04.848 UTC Thu Dec
RTR_SantoTome#13 2012, PeerAddress 115, PeerSubAddress , DisconnectCause 10  , DisconnectText normal call clearing (16), ConnectTime 12:18:24.718 UTC Thu Dec 13 2012, DisconnectTime 12:18:25.388 UTC Thu Dec 13 2012, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 29, TransmitBytes 4640, ReceivePackets 6, ReceiveBytes 662
Dec 13 12:18:25: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 12:18:04.844,cgn:,cdn:115,frs:0,fid:6353,fcid:F2C9FD6B445511E29CC2A4245B31D7B,legID:39C1,bguid:F2C9FD6B445511E29CC
RTR_SantoTome#2A42405B31D7B
Dec 13 12:18:25: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId F2C9FD6B445511E29CC2A4245B31D7B, SetupTime 12:17:51.072 UTC Thu Dec 13 2012, PeerAddress , PeerSubAddress , DisconnectCause 10  , DisconnectText normal call clearing (16), ConnectTime 12:18:24.712 UTC Thu Dec 13 2012, DisconnectTime 12:18:25.412 UTC Thu Dec 13 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 460, TransmitBytes 76982, ReceivePackets 29, ReceiveBytes 4640
Dec 13 12:18:25: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 12:17:51.076,cgn:,cdn:,frs:0,fid:6352,fcid:F2C9FD6B4455
 
 
 
 
_________________________________________________________________________________________________________________________________________
 
 
DEBUG VOIP APP SCRIPT
 

Dec 13 12:19:37.620: //14789//TCL :/tcl_InfotagObjCmd:  infotag get leg_isdid
Dec 13 12:19:37.620: //14789//TCL :/tcl_InfotagGetObjCmd: infotag get leg_isdid
Dec 13 12:19:37.620: //14789//AFW_:/vtr_lg_incdid: argc 2 argindex 2
Dec 13 12:19:37.620: //14789//TCL :/tcl_InfotagObjCmd:  infotag get leg_dnis
Dec 13 12:19:37.620: //14789//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Dec 13 12:19:37.620: //14789//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Dec 13 12:19:37.620: //14789//TCL :/tcl_LegObjCmd:  leg proceeding leg_incoming
Dec 13 12:19:37.620: //14789//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
Dec 13 12:19:37.620: //14789//AFW_:/vtd_lg_incoming: argc 2
Dec 13 12:19:37.620: //14789//AFW_:/vtd_lg_incoming: Legs [14789 ]
Dec 13 12:19:37.620: //14789//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:19:37.620: //14789//TCL :/tcl_LegObjCmd:  leg setup  callInfo leg_incoming
Dec 13 12:19:37.620: //14789//AFW_:/vtd_lg_incoming: argc 4
Dec 13 12:19:37.620: //14789//AFW_:/vtd_lg_incoming: Legs [14789 ]
Dec 13 12:19:37.620: //14789//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:19:37.624: //14789//TCL :/tcl_FSMObjCmd:  fsm setstate PLACECALL
Dec 13 12:19:37.624: //14789//TCL :/tcl_FSMSetStateObjCmd: setstate setstate PLACECALL
Dec 13 12:19:37.624: //14789//TCL :/tcl_InfotagObjCmd:  infotag get evt_status
Dec 13 12:19:37.624: //14789//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Dec 13 12:19:37.624: //14789//AFW_:/vtr_ev_status: argc 2 argindex 2
Dec 13 12:19:37.624: //14789//TCL :/tcl_PutsObjCmd:
 Valor del Leg setup
Dec 13 12:19:37.624:
Dec 13 12:19:37.624: //14789//TCL :/tcl_PutsObjCmd:
Disco un numero invalido
Dec 13 12:19:37.624:
Dec 13 12:19:37.624: //14789//PACK:/tcl_MediaObjCmd:  media play leg_incoming flash:sp_dest_unreachable.au
Dec 13 12:19:37.624: //14789//PACK:/tcl_MediaPlayObjCmd:  play leg_incoming flash:sp_dest_unreachable.au
Dec 13 12:19:37.624: //14789//AFW_:/vtd_lg_incoming: argc 3
Dec 13 12:19:37.624: //14789//AFW_:/vtd_lg_incoming: Legs [14789 ]
Dec 13 12:19:37.624: //14789//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:19:37.624: //14789//PACK:/Media_Play_Start: 
Dec 13 12:19:37.624: //14789//TCL :/tcl_FSMObjCmd:  fsm setstate ROBERTO
Dec 13 12:19:37.624: //14789//TCL :/tcl_FSMSetStateObjCmd: setstate setstate ROBERTO
Dec 13 12:19:37.676: //14789//TCL :/tcl_InfotagObjCmd:  infotag get evt_legs
Dec 13 12:19:37.676: //14789//TCL :/tcl_InfotagGetObjCmd: infotag get evt_legs
Dec 13 12:19:37.676: //14789//AFW_:/vtr_ev_legs: argc 2
Dec 13 12:19:37.676: //14789//AFW_:/vtr_ev_legs: EVCALLID [14789]
Dec 13 12:19:37.676: //14789//TCL :/tcl_PutsObjCmd:
 Incoming leg 14789
Dec 13 12:19:37.676:
Dec 13 12:19:37.676: //14789//TCL :/tcl_InfotagObjCmd:  infotag get evt_status
Dec 13 12:19:37.676: //14789//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Dec 13 12:19:37.676: //14789//AFW_:/vtr_ev_status: argc 2 argindex 2
Dec 13 12:19:37.676: //14789//TCL :/tcl_PutsObjCmd:
 evt_status= ms_001
Dec 13 12:19:37.676:
Dec 13 12:19:37.676: //14789//PACK:/tcl_MediaObjCmd:  media play leg_incoming flash:mensaje.au
Dec 13 12:19:37.676: //14789//PACK:/tcl_MediaPlayObjCmd:  play leg_incoming flash:mensaje.au
Dec 13 12:19:37.676: //14789//AFW_:/vtd_lg_incoming: argc 3
Dec 13 12:19:37.676: //14789//AFW_:/vtd_lg_incoming: Legs [14789 ]
Dec 13 12:19:37.676: //14789//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:19:37.676: //14789//PACK:/Media_Play_Start: 
Dec 13 12:19:37.676: //14789//TCL :/tcl_LegObjCmd:  leg collectdigits leg_incoming param
Dec 13 12:19:37.676: //14789//AFW_:/vtd_lg_incoming: argc 3
Dec 13 12:19:37.676: //14789//AFW_:/vtd_lg_incoming: Legs [14789 ]
Dec 13 12:19:37.676: //14789//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:19:37.676: //14789//TCL :/tcl_PutsObjCmd:
Estoy colectando digitos nuevamente
Dec 13 12:19:37.676:
Dec 13 12:19:53.028: //14789//TCL :/tcl_InfotagObjCmd:  infotag get evt_status
Dec 13 12:19:53.028: //14789//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Dec 13 12:19:53.028: //14789//AFW_:/vtr_ev_status: argc 2 argindex 2
Dec 13 12:19:53.028: //14789//TCL :/tcl_InfotagObjCmd:  infotag get evt_legs
Dec 13 12:19:53.028: //14789//TCL :/tcl_InfotagGetObjCmd: infotag get evt_legs
Dec 13 12:19:53.028: //14789//AFW_:/vtr_ev_legs: argc 2
Dec 13 12:19:53.028: //14789//AFW_:/vtr_ev_legs: EVCALLID [14789]
Dec 13 12:19:53.028: //14789//TCL :/tcl_InfotagObjCmd:  infotag get evt_dcdigits
Dec 13 12:19:53.028: //14789//TCL :/tcl_InfotagGetObjCmd: infotag get evt_dcdigits
Dec 13 12:19:53.028: //14789//AFW_:/vtr_ev_dcdigits: argc 2
Dec 13 12:19:53.028: //14789//AFW_:/vtr_ev_dcdigits: DCDIGITS [115]
Dec 13 12:19:53.028: //14789//TCL :/tcl_InfotagObjCmd:  infotag get evt_legs
Dec 13 12:19:53.028: //14789//TCL :/tcl_InfotagGetObjCmd: infotag get evt_legs
Dec 13 12:19:53.028: //14789//AFW_:/vtr_ev_legs: argc 2
Dec 13 12:19:53.028: //14789//AFW_:/vtr_ev_legs: EVCALLID [14789]
Dec 13 12:19:53.028: //14789//TCL :/tcl_PutsObjCmd:  Estoy usando el leg 14789 y el destino es 115
Dec 13 12:19:53.028:
Dec 13 12:19:53.028: //14789//TCL :/tcl_LegObjCmd:  leg setup 115 callInfo leg_incoming
Dec 13 12:19:53.028: //14789//AFW_:/vtd_lg_incoming: argc 4
Dec 13 12:19:53.028: //14789//AFW_:/vtd_lg_incoming: Legs [14789 ]
Dec 13 12:19:53.028: //14789//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Dec 13 12:20:01: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2, ConnectionId 407D5837445611E29CDAA4245B31D7B, SetupTime 12:20:01.440 UTC Thu Dec 13 2012, PeerAddress 150, PeerSubAddress , DisconnectCause 1C  , DisconnectText invalid number (28), ConnectTime 12:20:01.460 UTC Thu Dec 13 2012, DisconnectTime 12:20:01.460 UTC Thu Dec 13 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
Dec 13 12:20:01: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 12:20:01.440,cgn:150,cdn:3,frs:0,fid:6358,fcid:407D5837445611E29CDAA4245B31D7B,legID:39C9,bguid:407D5837445611E29CDAA42405B31D7B
Dec 13 12:20:11.548: //14789//TCL :/tcl_CallObjCmd:  call close
Dec 13 12:20:11.548: //14789//TCL :/tcl_CallCloseObjCmd:  close
Dec 13 12:20:11: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2, ConnectionId 324B4EF8445611E29CD4A4245B31D7B, SetupTime 12:19:53.040 UTC Thu Dec 13 2012, PeerAddress 115, PeerSubAddress , DisconnectCause 56  , DisconnectText call cleared (86), ConnectTime 12:20:11.560 UTC Thu Dec 13 2012, DisconnectTime 12:20:11.560 UTC Thu Dec 13 2012, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
Dec 13 12:20:11: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 12:19:53.028,cgn:,cdn:115,frs:0,fid:6357,fcid:324B4EF8445611E29CD4A4245B31D7B,legID:39C8,bguid:324B4EF8445611E29CD4A42405B31D7B
Dec 13 12:20:11: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId 324B4EF8445611E29CD4A4245B31D7B, SetupTime 12:19:37.614 UTC Thu Dec 13 2012, PeerAddress , PeerSubAddress , DisconnectCause 56  , DisconnectText call cleared (86), ConnectTime 12:20:11.544 UTC Thu Dec 13 2012, DisconnectTime 12:20:11.544 UTC Thu Dec 13 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 458, TransmitBytes 76944, ReceivePackets 0, ReceiveBytes 0
Dec 13 12:20:11: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 12:19:37.620,cgn:,cdn:,frs:0,fid:6356,fcid:324B4EF8445611E29CD4A4245B31D7B,legID:39C5,bguid:324B4EF8445611E29CD4A42405B31D7B

Subject: RE: aa Tcl on cme with 3905
Replied by: Yaw-Ming Chen on 13-12-2012 11:40:05 AM
I may know your issue. Please show me your setup procedure. How you get  DNIS.
 
Thanks

Subject: RE: aa Tcl on cme with 3905
Replied by: Lisandro Quinteros on 13-12-2012 12:47:20 PM
Ill be happy if you are rigth...Here its goes the whole script...
 
Thanks in advance...
 
proc init { } {
 
global param
 
 
 
set param(interruptPrompt) true
 
set param(abortKey) *
 
set param(terminationKey) #
 
set param(dialPlanTerm) false
 
set param(initialDigitTimeout) 3
 
set param(dialPlan) false
 
set param(maxDigits) 4
 
set param(interDigitTimeout) 4
 
 
 
}
 
 
 
proc act_Setup { } {
 
global dest
 
global beep
 
global param
 
set beep 0
 
 
 
if { [infotag get leg_isdid] } {
 
set dest [infotag get leg_dnis]
 
leg proceeding leg_incoming
 
leg setup $dest callInfo leg_incoming
 
fsm setstate PLACECALL
 
} else {
 
leg setupack leg_incoming
 
media play leg_incoming flash:mensaje.au
 
 
 
 
 
leg collectdigits leg_incoming param
 
puts "\n estoy colectando"
 
}
 
 
 
 
 
}
 
 
 
proc act_GotDest { } {
 
global dest
 
set status [infotag get evt_status]
 
puts "\ Estado [infotag get evt_status] "
 
if { $status == "cd_001" } {
 
# Si no disca ningún número de interno va a la operadora
 
leg proceeding leg_incoming
 
leg setup 7998 callInfo leg_incoming
 
} elseif { $status == "cd_005" } {
 
set dest [infotag get evt_dcdigits]
 
puts "\estoy por conectar $dest"
 
leg proceeding leg_incoming
 
leg setup $dest callInfo leg_incoming
 
 
 
} else {
 
media play leg_incoming flash:sp_dest_unreachable.au
 
puts "\nEl numero [infotag get evt_dcdigits] No matchea con ningun dial peer"
 
puts "\nLa variable dest tiene $dest "
 
puts "\nLa llamada [infotag get con_all] tiene un evento $status "
 
fsm setstate ROBERTO
 
 
 
}
 
}
 
 
 
 
 
proc act_collect_again { } {
 
 
 
global param
 
global dest
 
puts "\n Incoming leg [infotag get evt_legs]"
 
puts "\n evt_status= [infotag get evt_status]"
 
media play leg_incoming flash:mensaje.au
 
leg collectdigits leg_incoming param
 
puts "\nEstoy colectando digitos nuevamente"
 
}
 
proc act_GotDest_again { } {
 
 
 
set status [infotag get evt_status]
 
if { $status == "cd_001" } {
 
 
 
# Si no disca ningún número de interno va a la operadora
 
leg setup 7999 callInfo leg_incoming
 
} else {
 
set leg [infotag get evt_legs]
 
set dest [infotag get evt_dcdigits]
 
puts " Estoy usando el leg [infotag get evt_legs] y el destino es $dest"
 
leg setup $dest callInfo leg_incoming
 
}
 
}
 
proc act_CallSetupDone { } {
 
global beep
 
 
 
set status [infotag get evt_status]
 
puts "\n Valor del Leg setup "
 
 
 
if { $status == "ls_006"} {
 
puts "\nLa extension esta ocupada"
 
media play leg_incoming flash:sp_dest_busy.au
 
fsm setstate ROBERTO
 
} elseif { $status == "ls_000" } {
 
puts "\nConectado"
 
} elseif { $status == "ls_004"} {
 
puts "\nDisco un numero invalido"
 
media play leg_incoming flash:sp_dest_unreachable.au
 
fsm setstate ROBERTO
 
} elseif { $status == "ls_003"} {
 
puts "\ Paso la llamada pero tiene problemas al conectarla"
 
media play leg_incoming flash:sp_dest_unreachable.au
 
fsm setstate ROBERTO
 
} elseif { $status == "ls_007"} {
 
puts "\ Pequeño truco ocupado"
 
media play leg_incoming flash:sp_dest_busy.au
 
fsm setstate ROBERTO
 
} else {
 
puts "\n Leg setup status [infotag get evt_status]"
 
puts " Cierro la llamada "
 
puts "\n Incoming leg [infotag get evt_legs]"
 
call close
 
}
 
}
 
 
 
 
 
proc act_Timer { } {
 
global beep
 
global incoming
 
global outgoing
 
 
 
set incoming [infotag get leg_incoming]
 
set outgoing [infotag get leg_outgoing]
 
 
 
if { $beep == 0 } {
 
#insert a beep ...to the caller
 
connection destroy con_all
 
set beep 1
 
} else {
 
connection destroy con_all
 
fsm setstate LASTWARN
 
}
 
}
 
 
 
proc act_LastWarn { } {
 
media play leg_incoming flash:out_of_time.au
 
}
 
 
 
proc act_Destroy { } {
 
media play leg_incoming flash:beep.au
 
}
 
 
 
proc act_Beeped { } {
 
global incoming
 
global outgoing
 
 
 
connection create $incoming $outgoing
 
}
 
 
 
proc act_ConnectedAgain { } {
 
timer start leg_timer 10 leg_incoming
 
}
 
 
 
proc act_Ignore { } {
 
# Dummy
 
puts "Event Capture"
 
}
 
 
 
proc act_Cleanup { } {
 
call close
 
}
 
 
 
init
 
 
 
#----------------------------------
 
# State Machine
 
#----------------------------------
 
set fsm(any_state,ev_disconnected) "act_Cleanup same_state"
 
 
 
set fsm(CALL_INIT,ev_setup_indication) "act_Setup GETDEST"
 
 
 
set fsm(GETDEST,ev_collectdigits_done) "act_GotDest PLACECALL"
 
 
 
set fsm(PLACECALL,ev_setup_done) "act_CallSetupDone CALLACTIVE"
 
set fsm(ROBERTO,ev_media_done) "act_collect_again GETDEST_AGAIN"
 
　
 
set fsm(GETDEST_AGAIN,ev_collectdigits_done) "act_GotDest_again PLACECALL"
 
 
 
　
 
 
 
set fsm(CALLACTIVE,ev_disconnected) "act_Cleanup CALLDISCONNECT"
 
set fsm(CALLDISCONNECT,ev_disconnected) "act_Cleanup same_state"
 
set fsm(CALLDISCONNECT,ev_media_done) "act_Cleanup same_state"
 
set fsm(CALLDISCONNECT,ev_disconnect_done) "act_Cleanup same_state"
 
set fsm(CALLDISCONNECT,ev_leg_timer) "act_Cleanup same_state"
 
 
 
fsm define fsm CALL_INIT
 

Subject: RE: aa Tcl on cme with 3905
Replied by: Yaw-Ming Chen on 13-12-2012 01:45:27 PM
Try adding "leg connect leg_incoming" before collecting digits.

Subject: RE: aa Tcl on cme with 3905
Replied by: Lisandro Quinteros on 13-12-2012 06:21:17 PM
ill put it just up to leg collecdigits leg_incoming and try... but i should akso put it before connectind did dial calls? I'll try and let you know.
thanks very mucj

Subject: RE: aa Tcl on cme with 3905
Replied by: Lisandro Quinteros on 13-12-2012 09:40:11 PM
Hi I did what you suggested me and got this error from debug voip app script
 
 
ec 14 02:50:05.502: //560//TCL :/tcl_PutsObjCmd: 
 estoy colectando
Dec 14 02:50:05.502: 
RTR_SantoTome#
Dec 14 02:50:09.246: //560//TCL :/tcl_PutsObjCmd:  Estado cd_005 
Dec 14 02:50:09.246: 
Dec 14 02:50:09.246: //560//TCL :/tcl_PutsObjCmd: estoy por conectar 1111
Dec 14 02:50:09.246: 
Dec 14 02:50:09.246: //560//AFW_:/AFW_FSM_Drive: Tcl_Eval to drive FSM inside Tcl modulespace. code=1 code=ERROR
Dec 14 02:50:09.246: TCL script failure 
        Result:
                         Illegal Operation: Leg not incoming or in wrong state
Dec 14 02:50:09.246:    TCL script failure errorInfo:
                        Illegal Operation: Leg not incoming or in wrong state
    while executing
"leg proceeding leg_incoming
    (procedure "act_GotDest" line 14)
    invoked from within
"act_GotDest"                    
 
 
 
 
 
 
 
So I delete leg proceeding leg_incoming  on act_GotDest
The call now works but I coundt pick up yet
From some logs I read this maybe could help...
When I press pickup or pickup group I read invalid number and a busy tone. Also try direct and no direct call pickup.. or pickup group and group number or extension but the same result.
 
 
 
 
 
Dec 13 23:56:53: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId BBB4CE0C44D011E283A6D683B8792057, SetupTime 23:56:46.767 ARG Thu Dec 13 2012, PeerAddress 124, PeerSubAddress , DisconnectCause 1C  , DisconnectText invalid number (28), ConnectTime 23:56:53.417 ARG Thu Dec 13 2012, DisconnectTime 23:56:53.417 ARG Thu Dec 13 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
Dec 13 23:56:53: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 23:56:46.761,cgn:124,cdn:,frs:0,fid:228,fcid:BBB4CE0C44D011E283A6D683B8792057,legID:24E,bguid:BBB4CE0C44D011E283A6D683B8792057
RTR_SantoTome#
Dec 13 23:56:59: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId C1455E3C44D011E283AAD683B8792057, SetupTime 23:56:56.099 ARG Thu Dec 13 2012, PeerAddress 124, PeerSubAddress , DisconnectCause 1C  , DisconnectText invalid number (28), ConnectTime 23:56:59.449 ARG Thu Dec 13 2012, DisconnectTime 23:56:59.449 ARG Thu Dec 13 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
Dec 13 23:56:59: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:12/13/2012 23:56:56.101,cgn:124,cdn:,frs:0,fid:229,fcid:C1455E3C44D011E283AAD683B8792057,legID:24F,bguid:C1455E3C44D011E283AAD683B8792057
 
thanks again..Lisandro

Subject: RE: aa Tcl on cme with 3905
Replied by: Yaw-Ming Chen on 13-12-2012 10:56:23 PM
You should only put it in act_setup procedure

Also you don't do "leg proceeding leg_incoming" in "act_GotDest"      
 
to be safe check leg status in act_setup procedure like the following:
 
    leg setupack leg_incoming
    set legID [string trim [infotag get leg_incoming]]
               set legState [infotag get leg_state $legID]
               puts -nonewline "TCL Emergency: +++ legState = $legState +++"
               #check leg state before do "leg connect
               #to make sure leg is not connected already
               if {$legState != "lg_005" && $legState != "lg_008"} {
                   if {$legState == "lg_001"} {
                       leg setupack leg_incoming
                       leg proceeding leg_incoming
                       leg connect leg_incoming
                   } elseif {$legState == "lg_002"} {
                       leg proceeding leg_incoming
                       leg connect leg_incoming
                   } else {
                       leg connect leg_incoming
                   }
               }
              
               # lg_001 --> LEG_INCOMING_FIRST
               # lg_002 --> LEG_INCACKED
               # lg_003 --> LEG_INCPROCEED
               # lg_005 --> LEG_INCCONNECTED
               # lg_008 --> LEG_OUTPROCEED
              
 
 

Subject: RE: aa Tcl on cme with 3905
Replied by: Lisandro Quinteros on 14-12-2012 10:11:46 AM
hi I have modified act_setup script run fine but i could pickup... I wonder if its a problem with CME with Cisco 3905 sip phones..???
I just need a welcome message.. the user should dial the extension number is known or wait for timeout and ring operator dn..

Subject: RE: aa Tcl on cme with 3905
Replied by: Yaw-Ming Chen on 14-12-2012 11:34:31 AM
If you think it's something to do with particaular phone try this way:
do call setup without leg_incoming
leg setup $dest callInfo

when receive setupdone with ls_000 than do "connection create"
 

Subject: RE: aa Tcl on cme with 3905
Replied by: Yaw-Ming Chen on 14-12-2012 01:26:39 PM
Just like what debug tell us, need to include leg ids in command
 args: should be "create {<callID1>|Info-Tag} {<callId2>|Info-Tag}"
 
Please refer to programming guide

Subject: RE: aa Tcl on cme with 3905
Replied by: Lisandro Quinteros on 14-12-2012 01:07:36 PM
I got this from debug...
 
 
 
TCL script failure 
        Result:
                         wrong # args: should be "create {<callID1>|Info-Tag} {<callId2>|Info-Tag}"
Dec 14 18:49:51.673:    TCL script failure errorInfo:
                        wrong # args: should be "create {<callID1>|Info-Tag} {<callId2>|Info-Tag}"
    while executing
"connection create
    (procedure "act_CallSetupDone" line 12)
    invoked from within
"act_CallSetupDone"
 
 
 
 
My act_CallSetupDone ends like this..
 
 
proc act_CallSetupDone { } {
    global beep
 
    set status [infotag get evt_status]
puts "\n Valor del Leg setup "
 
    if { $status == "ls_006"} {
        puts "\nLa extension esta ocupada"
media play leg_incoming flash:sp_dest_busy.au
fsm setstate ROBERTO
   } elseif { $status == "ls_000" } {
   connection create
                  puts "\nConectado"
   } elseif { $status == "ls_004"} {
 
 
Any idea??
 
 
Whats the difference between leg setup and handoff appl leg_incoming default "DESTINATION=$dest"
Its possible to collect digit and then use handoff appl leg_incoming default "DESTINATION=$dest" ? couse one of tcl I found just play welcome message on E1 DID .. and use handoff appl leg_incoming default "DESTINATION=$dest" and with that capture works fine but I cant collect digit with that TCL so I should add collect digit if possible..
Thanks..again...

Subject: RE: aa Tcl on cme with 3905
Replied by: Lisandro Quinteros on 17-12-2012 06:42:45 AM
I always goes back to guide its really helpful and the only way to undestand a little about tcl.. But my problem is where should I capture the info tag of call ids
What I am thinkings is..
Declare variables..
 
global incoming 
global outgoing

Its right if I do all this on ls_000 ??
 
set incoming [infotag get leg_incoming] 
set outgoing [infotag get leg_outgoing]
connection create $incoming $outgoing
 
I heard te welcome message.. dial dn number... I dont heard DIAL TONE so ill would have to send dial tone o inco leg.. but doesnt mother for this test... The dn is ringing ok but still cant capture the call  
Any idea ?
If I pickup the call the callworks great..
thanks Lisandro

Subject: RE: aa Tcl on cme with 3905
Replied by: Yaw-Ming Chen on 17-12-2012 10:01:12 AM
Lisandro Quinteros:
I always goes back to guide its really helpful and the only way to undestand a little about tcl.. But my problem is where should I capture the info tag of call ids
What I am thinkings is..
Declare variables..
 
global incoming 
global outgoing

Its right if I do all this on ls_000 ??
 
set incoming [infotag get leg_incoming] 
set outgoing [infotag get leg_outgoing]
connection create $incoming $outgoing
 
I heard te welcome message.. dial dn number... I dont heard DIAL TONE so ill would have to send dial tone o inco leg.. but doesnt mother for this test... The dn is ringing ok but still cant capture the call  
Any idea ?
If I pickup the call the callworks great..
thanks Lisandro

 
I am sorry that I gave copied the wrong command
I meant to say use "connection create" command
connection create {legID1 | info-tag1} {legID2 | info-tag2}

You can do connection create leg_coming leg_outgoing
or you you onlt have two legs do "connection create leg_all"
 
 
 

Subject: RE: aa Tcl on cme with 3905
Replied by: Lisandro Quinteros on 18-12-2012 10:34:47 AM
Still have no luck ...
I call to pstn number.. heard welcome message.. dial extension numer (dn 150) phone dn 150 start ringing.. If I hook of dn 150 the call complete great...
But if I insted try to capture (pickup call) that call from other dn (ex dn 115) I herd bussy tone in the phone where I tried to capture.. and original dn continue ringing (dn 150 continue ringing as if nothing happend)
Any suggestion?
I am starting to loose my head with this...!
Thanks in advance..
Labels:
0 Helpful
Comments
john.wallace
Level 1
Level 1
‎06-16-2015 11:13 AM

Hola Lisandro, tengo el mismo problema con un tcl de Bienvenida, tu pudiste resolver este problema?

Saludos,

Raghavendra G V
Cisco Employee
Cisco Employee
‎06-16-2015 10:15 PM

Hi John,

Please let us know what issue your facing with welcome prompt. You can raise Dev Net support case.

For more information please refer below link.

Cisco DevNet Support

Thanks,

Raghavendra

Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community:

Quick Links
Discussions
Customers Also Viewed These Support Documents