aa Tcl on cme with 3905

Version 1
    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..