sh run Building configuration... Current configuration : 14210 bytes ! ! Last configuration change at 11:00:31 Yemen Sun Jan 4 2015 by sfd version 15.1 service timestamps debug datetime msec service timestamps log datetime msec no service password-encryption ! hostname SFDHQ ! boot-start-marker boot system flash:c2800nm-ipvoicek9-mz.151-4.M8.bin boot-end-marker ! ! enable password cisco ! aaa new-model ! ! aaa authentication login SMART line aaa authentication login sbyb local --More--  ! ! ! ! ! aaa session-id common clock timezone Yemen 3 0 ! dot11 syslog ip source-route ! ! ip cef ! ip dhcp excluded-address 10.100.1.20 10.100.1.25 ! ip dhcp pool VOICE network 10.100.1.0 255.255.255.0 option 150 ip 10.100.1.20 default-router 10.100.1.20 domain-name SFDHQ.com ! ! --More--  ip name-server 172.16.1.40 ip name-server 8.8.8.8 no ipv6 cef multilink bundle-name authenticated ! ! ! ! ! ! trunk group 1 ! ! ! voice service voip allow-connections h323 to h323 allow-connections h323 to sip no supplementary-service h450.2 no supplementary-service h450.3 supplementary-service h450.12 advertise-only sip registrar server expires max 36000 min 600 ! --More--  voice class codec 5 codec preference 1 g729r8 codec preference 2 g711ulaw ! ! ! ! voice translation-rule 1 rule 1 /^011/ // ! voice translation-rule 2 rule 1 /^1\(..\)$/ /0011\1/ ! voice translation-rule 3 rule 1 /^004/ // ! voice translation-rule 4 rule 1 /^008/ // ! ! voice translation-profile To-DhAMAR translate calling 2 translate called 1 --More--  ! voice translation-profile To-Hod translate calling 2 translate called 3 ! voice translation-profile To-Taiz translate calling 2 translate called 4 ! ! voice-card 0 dspfarm dsp services dspfarm ! crypto pki token default removal timeout 0 ! crypto pki trustpoint TP-self-signed-37959023 enrollment selfsigned subject-name cn=IOS-Self-Signed-Certificate-37959023 revocation-check none rsakeypair TP-self-signed-37959023 ! ! --More--  crypto pki certificate chain TP-self-signed-37959023 certificate self-signed 01 30820227 30820190 A0030201 02020101 300D0609 2A864886 F70D0101 05050030 2F312D30 2B060355 04031324 494F532D 53656C66 2D536967 6E65642D 43657274 69666963 6174652D 33373935 39303233 301E170D 31343034 32383034 33323438 5A170D32 30303130 31303030 3030305A 302F312D 302B0603 55040313 24494F53 2D53656C 662D5369 676E6564 2D436572 74696669 63617465 2D333739 35393032 3330819F 300D0609 2A864886 F70D0101 01050003 818D0030 81890281 81009E0B EFA121B9 1644A339 161CC125 3199BE04 F131E5E4 4822D066 69B63BBB BA40E5B4 DD862F19 1E108FD4 D151A98E 31C8947E 2F4F27E4 3420B674 7E6E6DC5 D17DDF66 2EFCA367 04A9B82E 89645727 E669B18B 2550F7C6 BE1438E8 6581B6D3 1FF0EC5D FA81C1E8 F3D1AF95 55684D97 7C4A5314 C6A37C2D 321CD465 C3325E0F 5F670203 010001A3 53305130 0F060355 1D130101 FF040530 030101FF 301F0603 551D2304 18301680 1452C97E 3F8D6AF1 22E84F88 6F3A7B7F CBFE1477 9D301D06 03551D0E 04160414 52C97E3F 8D6AF122 E84F886F 3A7B7FCB FE14779D 300D0609 2A864886 F70D0101 05050003 8181001F F0B7F22B 719A8E9E D625EFB5 9D03F4BA 1F3C9D60 007A83A5 FB271B0B AB8C1B83 F29FFFB5 6C456C66 DF457569 E8514F56 055374E2 E6859DB4 5BC36027 B01B030D DC1B8202 377844B4 7A1A9BD4 32D5F8B9 A2FF5474 D5013A1E 75C08232 25620609 030FEBCA DE7A9504 7035F0C1 A213481C 783674EC E5A18D47 A4616525 F83270 quit ! ! --More--  license udi pid CISCO2811 sn FHK1436F30H archive log config hidekeys username admin privilege 15 password 0 s@5 username sfd privilege 15 password 0 sfd123 ! ! ip ssh version 2 ! ! ! ! ! interface FastEthernet0/0 no ip address duplex auto speed auto ! interface FastEthernet0/0.11 encapsulation dot1Q 11 ip address 10.100.1.20 255.255.255.0 h323-gateway voip interface --More--   h323-gateway voip bind srcaddr 10.100.1.20 ! interface FastEthernet0/1 ip address 172.16.1.6 255.255.255.0 duplex auto speed auto ! ip forward-protocol nd ! ip http server ip http authentication local ip http secure-server ip http path flash:cme-files/GUI ! ip route 0.0.0.0 0.0.0.0 172.16.1.20 ! ! snmp-server community sbybsnmp RO ! tftp-server flash:/cme-files/apps31.9-1-1TH1-16.sbn alias apps31.9-1-1TH1-16.sbn tftp-server flash:/cme-files/cnu31.9-1-1TH1-16.sbn alias cnu31.9-1-1TH1-16.sbn tftp-server flash:/cme-files/cvm31sccp.9-1-1TH1-16.sbn alias cvm31sccp.9-1-1TH1-16.sbn --More--  tftp-server flash:/cme-files/dsp31.9-1-1TH1-16.sbn alias dsp31.9-1-1TH1-16.sbn tftp-server flash:/cme-files/jar31sccp.9-1-1TH1-16.sbn alias jar31sccp.9-1-1TH1-16.sbn tftp-server flash:/cme-files/SCCP31.9-1-1SR1S.loads alias SCCP31.9-1-1SR1S.loads tftp-server flash:/cme-files/term31.default.loads alias term31.default.loads tftp-server flash:/cme-files/P00308010200.bin alias P00308010200.bin tftp-server flash:/cme-files/P00308010200.loads alias P00308010200.loads tftp-server flash:/cme-files/P00308010200.sb2 alias P00308010200.sb2 tftp-server flash:/cme-files/P00308010200.sbn alias P00308010200.sbn tftp-server flash:/cme-files/apps41.9-1-1TH1-16.sbn alias apps41.9-1-1TH1-16.sbn tftp-server flash:/cme-files/cnu41.9-1-1TH1-16.sbn alias cnu41.9-1-1TH1-16.sbn tftp-server flash:/cme-files/cvm41sccp.9-1-1TH1-16.sbn alias cvm41sccp.9-1-1TH1-16.sbn tftp-server flash:/cme-files/dsp41.9-1-1TH1-16.sbn alias dsp41.9-1-1TH1-16.sbn tftp-server flash:/cme-files/jar41sccp.9-1-1TH1-16.sbn alias jar41sccp.9-1-1TH1-16.sbn tftp-server flash:/cme-files/SCCP41.9-1-1SR1S.loads alias SCCP41.9-1-1SR1S.loads tftp-server flash:/cme-files/term41.default.loads alias term41.default.loads tftp-server flash:/cme-files/term61.default.loads alias term61.default.loads tftp-server flash:/cme-files/apps45.9-1-1TH1-16.sbn alias apps45.9-1-1TH1-16.sbn tftp-server flash:/cme-files/cnu45.9-1-1TH1-16.sbn alias cnu45.9-1-1TH1-16.sbn tftp-server flash:/cme-files/cvm45sccp.9-1-1TH1-16.sbn alias cvm45sccp.9-1-1TH1-16.sbn --More--  tftp-server flash:/cme-files/dsp45.9-1-1TH1-16.sbn alias dsp45.9-1-1TH1-16.sbn tftp-server flash:/cme-files/jar45sccp.9-1-1TH1-16.sbn alias jar45sccp.9-1-1TH1-16.sbn tftp-server flash:/cme-files/SCCP45.9-1-1SR1S.loads alias SCCP45.9-1-1SR1S.loads tftp-server flash:/cme-files/term45.default.loads alias term45.default.loads tftp-server flash:/cme-files/term65.default.loads alias term65.default.loads tftp-server flash:/cme-files/apps70.9-1-1TH1-16.sbn alias apps70.9-1-1TH1-16.sbn tftp-server flash:/cme-files/cnu70.9-1-1TH1-16.sbn alias cnu70.9-1-1TH1-16.sbn tftp-server flash:/cme-files/cvm70sccp.9-1-1TH1-16.sbn alias cvm70sccp.9-1-1TH1-16.sbn tftp-server flash:/cme-files/dsp70.9-1-1TH1-16.sbn alias dsp70.9-1-1TH1-16.sbn tftp-server flash:/cme-files/jar70sccp.9-1-1TH1-16.sbn alias jar70sccp.9-1-1TH1-16.sbn tftp-server flash:/cme-files/SCCP70.9-1-1SR1S.loads alias SCCP70.9-1-1SR1S.loads tftp-server flash:/cme-files/term70.default.loads alias term70.default.loads tftp-server flash:/cme-files/term71.default.loads alias term71.default.loads tftp-server flash:/cme-files/S00105000400.sbn alias S00105000400.sbn tftp-server flash:/cme-files/B015-1-0-4.SBN alias B015-1-0-4.SBN tftp-server flash:/cme-files/B016-1-0-4.SBN alias B016-1-0-4.SBN tftp-server flash:/cme-files/BOOT69xx.0-0-0-14.zz.sgn alias BOOT69xx.0-0-0-14.zz.sgn tftp-server flash:/cme-files/DSP69xx.0-0-0-4.zz.sgn alias DSP69xx.0-0-0-4.zz.s tftp-server flash:/cme-files/SCCP69xx.9-1-1-0.zz.sgn alias SCCP69xx.9-1-1-0.zz.sgn --More--  tftp-server flash:/cme-files/OS79XX.TXT alias OS79XX.TXT tftp-server flash:/cme-files/P0S3-08-9-00.loads alias P0S3-08-9-00.loads tftp-server flash:/cme-files/P0 tftp-server flash:/cme-files/P0S3-08-9-00.sb2 alias P0S3-08-9-00.sb2 tftp-server flash:/cme-files/P003-08-9-00.bin alias P003-08-9-00.bin tftp-server flash:/cme-files/P003-08-9-00.sbn alias P003-08-9-00.sbn tftp-server flash:/cme-files/ringtones/Analog1.raw alias Analog1.raw tftp-server flash:/cme-files/ringtones/Analog2.raw alias Analog2.raw tftp-server flash:/cme-files/ringtones/AreYouThere.raw alias AreYouThere.raw tftp-server flash:/cme-files/ringtones/AreYouThereF.raw alias AreYouThereF.raw tftp-server flash:/cme-files/ringtones/Bass.raw alias Bass.raw tftp-server flash:/cme-files/ringtones/CallBack.raw alias CallBack.raw tftp-server flash:/cme-files/ringtones/Chime.raw alias Chime.raw tftp-server flash:/cme-files/ringtones/Classic1.raw alias Classic1.raw tftp-server flash:/cme-files/ringtones/Classic2.raw alias Classic2.raw tftp-server flash:/cme-files/ringtones/ClockShop.raw alias ClockShop.raw tftp-server flash:/cme-files/ringtones/DistinctiveRingList.xml alias DistinctiveRingList.xml tftp-server flash:/cme-files/ringtones/Drums1.raw alias Drums1.raw tftp-server flash:/cme-files/ringtones/FilmScore.raw alias FilmScore.raw tftp-server flash:/cme-files/ringtones/HarpSynth.raw alias HarpSynth.raw tftp-server flash:/cme-files/ringtones/Jamaica.raw alias Jamaica.raw tftp-server flash:/cme-files/ringtones/KotoEffect.raw alias KotoEffect.raw --More--  tftp-server flash:/cme-files/ringtones/MusicBox.raw alias MusicBox.raw tftp-server flash:/cme-files/ringtones/Piano1.raw alias Piano1.raw tftp-server flash:/cme-files/ringtones/Piano2.raw alias Piano2.raw tftp-server flash:/cme-files/ringtones/Pulse1.raw alias Pulse1.raw tftp-server flash:/cme-files/ringtones/Ring1.raw alias Ring1.raw tftp-server flash:/cme-files/ringtones/Ring3.raw alias Ring3.raw tftp-server flash:/cme-files/ringtones/Ring4.raw alias Ring4.raw tftp-server flash:/cme-files/ringtones/Ring5.raw alias Ring5.raw tftp-server flash:/cme-files/ringtones/Ring6.raw alias Ring6.raw tftp-server flash:/cme-files/ringtones/Ring7.raw alias Ring7.raw tftp-server flash:/cme-files/ringtones/Sax1.raw alias Sax1.raw tftp-server flash:/cme-files/ringtones/Sax2.raw alias Sax2.raw tftp-server flash:/cme-files/ringtones/Vibe.raw alias Vibe.raw ! ! ! control-plane ! ! ! ! mgcp profile default ! --More--  ! dial-peer cor custom name 911CALL name GSM-CALL name LOCAL-CALL name INT-CALL name CITY-CALL name CDMA-CALL name ALLCALLS1 ! ! dial-peer cor list EMERGENCY member 911CALL ! dial-peer cor list LOCAL member LOCAL-CALL ! dial-peer cor list GSM member GSM-CALL ! dial-peer cor list INTERNATIONAL member INT-CALL ! --More--  dial-peer cor list CITIES member CITY-CALL ! dial-peer cor list CDMA member CDMA-CALL ! dial-peer cor list EMG-USER member 911CALL ! dial-peer cor list EMG-LOC-USER member 911CALL member LOCAL-CALL ! dial-peer cor list EMG-LOC-CDMA-GSM-CITY-USER member 911CALL member GSM-CALL member LOCAL-CALL member CITY-CALL member CDMA-CALL ! dial-peer cor list UNLIMITED-USER member 911CALL member GSM-CALL --More--   member LOCAL-CALL member INT-CALL member CITY-CALL member CDMA-CALL ! dial-peer cor list EMG-LOC-CITY-CDMA-USER member 911CALL member LOCAL-CALL member CITY-CALL member CDMA-CALL ! dial-peer cor list EMG-LOC-CITY-USER member 911CALL member LOCAL-CALL member CITY-CALL ! ! dial-peer voice 1000 pots trunkgroup 1 corlist outgoing CDMA description CALLING YEMENMOBILE COMPANY destination-pattern 977....... forward-digits 9 --More--  ! dial-peer voice 1002 pots trunkgroup 1 corlist outgoing LOCAL description CALLING LOCAL-INSIDE SANAA destination-pattern 9[2-6]..... forward-digits 6 ! dial-peer voice 1003 pots trunkgroup 1 corlist outgoing CITIES description CALLING CITIES destination-pattern 90[2-9]...... forward-digits 8 ! dial-peer voice 1004 pots trunkgroup 10 corlist outgoing INTERNATIONAL description INTERNATIONAL CALLS destination-pattern 900T prefix 00 ! dial-peer voice 1005 pots --More--   trunkgroup 1 description SERVICES CALL destination-pattern 91.. forward-digits 3 ! dial-peer voice 1006 pots trunkgroup 1 description EMERGENCY CALL destination-pattern 199 forward-digits all ! dial-peer voice 1007 pots trunkgroup 1 corlist outgoing GSM description CALLING GSM MOBILE destination-pattern 97[0-1,3]....... forward-digits 9 ! dial-peer voice 10 pots destination-pattern 9714701096 forward-digits 9 ! dial-peer voice 91 voip --More--   translation-profile outgoing To-Hod destination-pattern 004... session protocol sipv2 session target ipv4:10.100.4.20 voice-class codec 5 dtmf-relay h245-alphanumeric h245-signal no vad ! dial-peer voice 92 voip translation-profile outgoing To-Taiz destination-pattern 008... session protocol sipv2 session target ipv4:10.100.8.20 voice-class codec 5 dtmf-relay h245-alphanumeric h245-signal no vad ! dial-peer voice 9 voip translation-profile outgoing To-DhAMAR destination-pattern 011... session protocol sipv2 session target ipv4:10.100.11.20 voice-class codec 5 --More--   dtmf-relay h245-alphanumeric h245-signal no vad ! dial-peer voice 8 voip destination-pattern 123 session target ipv4:10.100.8.20 codec g711ulaw no vad ! ! ! telephony-service max-ephones 35 max-dn 100 ip source-address 10.100.1.20 port 2000 system message SFDHQ load 7914 S00105000400.sbn load 7915-12 B015-1-0-4.SBN load 7915-24 B015-1-0-4.SBN load 7916-12 B016-1-0-4.SBN load 7916-24 B016-1-0-4.SBN load 7931 SCCP31.9-1-1SR1S.loads load 7960-7940 P00308010200.loads --More--   load 7941 SCCP41.9-1-1SR1S.loads load 7945 SCCP45.9-1-1SR1S.loads load 7961 SCCP41.9-1-1SR1S.loads load 7965 SCCP45.9-1-1SR1S.loads load 7970 SCCP70.9-1-1SR1S.loads load 7971 SCCP70.9-1-1SR1S.loads load 6921 SCCP69xx.9-1-1-0.loads load 6941 SCCP69xx.9-1-1-0.loads load 6961 SCCP69xx.9-1-1-0.loads time-zone 31 time-format 24 max-conferences 8 gain -6 moh flash:/music-on-hold.au web admin system name admin password s@5 dn-webedit time-webedit transfer-system full-consult transfer-pattern .... transfer-pattern 9.T transfer-pattern 1.. transfer-pattern 011... transfer-pattern 004... transfer-pattern 008... --More--   create cnf-files version-stamp 7960 Dec 31 2014 11:21:29 ! ! ephone-dn 1 dual-line number 113 allow watch ! ! ephone-dn 2 dual-line number 112 allow watch ! ! ephone-dn 3 dual-line number 111 allow watch ! ! ephone-dn 4 dual-line number 1723 pickup-group 1719 corlist incoming EMG-LOC-USER ! --More--  ! ephone-dn 10 number 1727 park-slot ! ! ephone 1 mac-address 10BD.18DC.9D7D max-calls-per-button 2 type 7931 button 1:1 ! ! ! ephone 2 mac-address 34DB.FDE4.422D max-calls-per-button 2 type 6921 button 1:2 ! ! ! ephone 3 --More--   mac-address 34DB.FDE4.443F max-calls-per-button 2 type 6921 button 1:3 ! ! ! ! line con 0 line aux 0 line vty 0 4 login authentication sbyb transport input all ! scheduler allocate 20000 1000 end SFDHQ# termi SFDHQ# terminal mo SFDHQ# terminal moni SFDHQ# terminal monitor SFDHQ#debug voip ccapi inout voip ccapi inout debugging is on SFDHQ#debug vpm all All Voice Port Module debugging is enabled Note: DSP message debugging is not part of this command. Use debug vpm dsp to enable it SFDHQ#debug voip vtsp all voip vtsp all debugging is on SFDHQ# *Jan 4 08:39:19.771: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=1736 systime=494857 *Jan 4 08:39:19.771: htsp_process_event: [50/0/3.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind *Jan 4 08:39:19.771: [50/0/3.1] get_local_station_id calling num=111 calling name= calling time=01/04 11:39 orig called= *Jan 4 08:39:19.771: TGRM: reg_invoke_tgrm_accept_call(0, TGRM_CALL_VOICE, TGRM_DIRECTION_IN, 50, 0, 3, 65535) *Jan 4 08:39:19.771: TGRM: Calling vtsp_tsp_call_setup_ind(). *Jan 4 08:39:19.771: TGRM: reg_invoke_tgrm_call_update(50, 0, 3, 65535, 0, TGRM_CALL_BUSY, TGRM_CALL_VOICE, TGRM_DIRECTION_IN) *Jan 4 08:39:19.771: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_allocate_cdb: CDB=0x4AC3F650 *Jan 4 08:39:19.771: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_do_call_setup_ind: Event=E_TSP_SETUP_IND Progress Indication=3, CarrierIDCode=, Info Trans Capability=0, Source Carrier ID=, tg_label_flag=0 *Jan 4 08:39:19.771: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_do_call_setup_ind: Calling Number=111, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed CLIR=FALSE, CLID Transparent=FALSE, Null Originating Calling Number=FALSE, Calling Translated=FALSE *Jan 4 08:39:19.775: //-1/027B929B809D/VTSP:(50/0/3):-1:-1:-1/vtsp_do_normal_call_setup_ind: *Jan 4 08:39:19.775: //-1/027B929B809D/VTSP:(50/0/3):-1:-1:-1/vtsp_timer: Timer Start Time=494857, Timer Value=180000(ms) *Jan 4 08:39:19.775: //-1/027B929B809D/VTSP:(50/0/3):-1:-1:-1/vtsp_do_normal_call_setup_ind: IC State Machine (ICSM) Added *Jan 4 08:39:19.775: //-1/027B929B809D/VTSP:(50/0/3):-1:-1:-1/vtsp_insert_cdb: *Jan 4 08:39:19.779: //-1/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_opened_cb: *Jan 4 08:39:19.779: //-1/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] *Jan 4 08:39:19.779: //-1/027B929B809D/VTSP:(50/0/3):-1:0:17/act_setup_ind_pend_success: *Jan 4 08:39:19.779: //-1/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_realloc_cdb: CDB=0x4AC3F650 *Jan 4 08:39:19.779: //-1/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_indicate_call: *Jan 4 08:39:19.779: //-1/027B929B809D/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=111 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 *Jan 4 08:39:19.783: //-1/027B929B809D/CCAPI/cc_api_call_setup_ind_common: Interface=0x4A9F3234, Call Info( Calling Number=111,(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=FALSE, Incoming Dial-peer=20003, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1 *Jan 4 08:39:19.783: //-1/027B929B809D/CCAPI/ccCheckClipClir: In: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:19.783: //-1/027B929B809D/CCAPI/ccCheckClipClir: Out: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:19.783: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:19.783: :cc_get_feature_vsa malloc success *Jan 4 08:39:19.783: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:19.783: cc_get_feature_vsa count is 1 *Jan 4 08:39:19.783: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:19.783: :FEATURE_VSA attributes are: feature_name:0,feature_time:1254732192,feature_id:54 *Jan 4 08:39:19.783: //54/027B929B809D/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=(TON=Unknown, NPI=Unknown)) *Jan 4 08:39:19.783: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_insert_cdb: *Jan 4 08:39:19.783: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer: Timer Start Time=494858, Timer Value=180000(ms) *Jan 4 08:39:19.783: //54/027B929B809D/CCAPI/cc_process_call_setup_ind: Event=0x4933FF90 *Jan 4 08:39:19.787: //54/027B929B809D/CCAPI/ccCallSetContext: Context=0x4ACC2690 *Jan 4 08:39:19.791: //54/027B929B809D/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 54 with tag 20003 to app "_ManagedAppProcess_Default" *Jan 4 08:39:19.791: //54/027B929B809D/CCAPI/ccCallSetupAck: Call Id=54 *Jan 4 08:39:19.791: //54/027B929B809D/CCAPI/cc_api_set_transfer_info: Transfer Number=, Transfer Reason=0x0 *Jan 4 08:39:19.791: //54/027B929B809D/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=TRUE, Tone=Dial Tone, Tone Direction=Network, Params=0x0, Call Id=54 *Jan 4 08:39:19.795: //54/027B929B809D/CCAPI/ccSetDigitTimeouts: Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms) *Jan 4 08:39:19.795: //54/027B929B809D/CCAPI/ccSetDigitTimeouts: Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms)) *Jan 4 08:39:19.795: //54/027B929B809D/CCAPI/ccCallReportDigits: (callID=0x36, digit_event=0x1, enable=TRUE, consume=FALSE) *Jan 4 08:39:19.795: //54/027B929B809D/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=54 *Jan 4 08:39:19.795: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: *Jan 4 08:39:19.795: //54/027B929B809D/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x4A9F3234, callID=0x36, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) *Jan 4 08:39:19.795: //54/027B929B809D/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x4A9F3234, Call Id=54 *Jan 4 08:39:19.795: //54/027B929B809D/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Jan 4 08:39:19.795: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: Digit Reporting=TRUE *Jan 4 08:39:19.795: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_SETUP_INDICATED, event:E_CC_SETUP_ACK] *Jan 4 08:39:19.795: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/act_setup_ind_ack: Fax Relay=TRUE, Dial Tone=TRUE, Digit Collect=TRUE, Overlap=FALSE, DID=FALSE *Jan 4 08:39:19.795: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=494859 *Jan 4 08:39:19.799: htsp_process_event: [50/0/3.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_OPEN_VOICE_CHANNEL *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ENC_CONFIG 2 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_SET_VOICE_PLAYOUT_DELAY *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ECHO_CANCELLER_CONTROL *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_VOICE_MODE codec=1 vad=0 size=160 edsp_state=0 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ECHO_CANCELLER_CONTROL *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_SET_GAINS *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.799: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.803: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.803: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_DTMF_MODE *Jan 4 08:39:19.803: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:19.803: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_ON 4 dir 1 *Jan 4 08:39:21.171: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:21.171: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=0 *Jan 4 08:39:21.171: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:21.171: //54/027B929B809D/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=0, DigitBeginFlags=0x0, Rtp Timestamp=0x310AB0, Rtp Expiration=0x0 *Jan 4 08:39:21.171: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:21.171: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_OFF *Jan 4 08:39:21.171: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:21.175: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=494997 *Jan 4 08:39:21.175: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=0 *Jan 4 08:39:21.175: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:21.175: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=0, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:21.175: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:21.175: htsp_digit_ready(50/0/3.1): digit = 0 *Jan 4 08:39:21.175: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:21.175: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_DTMF_MODE *Jan 4 08:39:21.663: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:21.663: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=1 *Jan 4 08:39:21.663: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:21.663: //54/027B929B809D/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=1, DigitBeginFlags=0x0, Rtp Timestamp=0x3187B0, Rtp Expiration=0x0 *Jan 4 08:39:21.663: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:21.663: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495046 *Jan 4 08:39:21.663: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=1 *Jan 4 08:39:21.663: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:21.663: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=1, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:21.663: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:21.663: htsp_digit_ready(50/0/3.1): digit = 1 *Jan 4 08:39:21.887: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:21.887: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=1 *Jan 4 08:39:21.887: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:21.887: //54/027B929B809D/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=1, DigitBeginFlags=0x0, Rtp Timestamp=0x3204B0, Rtp Expiration=0x0 *Jan 4 08:39:21.887: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:21.887: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495068 *Jan 4 08:39:21.887: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=1 *Jan 4 08:39:21.887: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:21.887: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=1, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:21.887: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:21.887: htsp_digit_ready(50/0/3.1): digit = 1 *Jan 4 08:39:22.575: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:22.575: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=1 *Jan 4 08:39:22.575: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:22.575: //54/027B929B809D/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=1, DigitBeginFlags=0x0, Rtp Timestamp=0x3281B0, Rtp Expiration=0x0 *Jan 4 08:39:22.575: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:22.575: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495137 *Jan 4 08:39:22.575: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=1 *Jan 4 08:39:22.575: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:22.575: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=1, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:22.575: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:22.575: htsp_digit_ready(50/0/3.1): digit = 1 *Jan 4 08:39:22.843: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:22.843: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=2 *Jan 4 08:39:22.847: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:22.847: //54/027B929B809D/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=2, DigitBeginFlags=0x0, Rtp Timestamp=0x32FEB0, Rtp Expiration=0x0 *Jan 4 08:39:22.847: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:22.847: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495164 *Jan 4 08:39:22.847: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=2 *Jan 4 08:39:22.847: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:22.847: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=2, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:22.847: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:22.847: htsp_digit_ready(50/0/3.1): digit = 2 *Jan 4 08:39:23.095: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:23.095: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=3 *Jan 4 08:39:23.095: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:23.095: //54/027B929B809D/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=3, DigitBeginFlags=0x0, Rtp Timestamp=0x337BB0, Rtp Expiration=0x0 *Jan 4 08:39:23.095: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:23.095: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495189 *Jan 4 08:39:23.095: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=3 *Jan 4 08:39:23.095: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:23.095: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=54, Digit=3, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:23.095: //54/027B929B809D/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:23.095: htsp_digit_ready(50/0/3.1): digit = 3 *Jan 4 08:39:23.099: //54/027B929B809D/CCAPI/ccCallReportDigits: (callID=0x36, digit_event=0x0, enable=FALSE, consume=FALSE) *Jan 4 08:39:23.099: //54/027B929B809D/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=54 *Jan 4 08:39:23.099: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: *Jan 4 08:39:23.099: //54/027B929B809D/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x4A9F3234, callID=0x36, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE) *Jan 4 08:39:23.099: //54/027B929B809D/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x4A9F3234, Call Id=54 *Jan 4 08:39:23.099: //54/027B929B809D/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Jan 4 08:39:23.099: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: Digit Reporting=FALSE *Jan 4 08:39:23.099: //54/027B929B809D/CCAPI/ccCallProceeding: Progress Indication=NULL(0) *Jan 4 08:39:23.107: //54/027B929B809D/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=9, Params=0x4ACC3BA0, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) *Jan 4 08:39:23.107: //54/027B929B809D/CCAPI/ccCheckClipClir: In: Calling Number=001111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:23.107: //54/027B929B809D/CCAPI/ccCheckClipClir: Out: Calling Number=001111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:23.107: //54/027B929B809D/CCAPI/ccCallSetupRequest: Destination Pattern=011..., Called Number=123, Digit Strip=FALSE *Jan 4 08:39:23.107: //54/027B929B809D/CCAPI/ccCallSetupRequest: Calling Number=001111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=123(TON=Unknown, NPI=Unknown), Redirect Number=, Display Info= Account Number=, Final Destination Flag=FALSE, Guid=027B929B-9324-11E4-809D-DE5D83C9FAA7, Outgoing Dial-peer=9 *Jan 4 08:39:23.107: //54/027B929B809D/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=001111 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=123 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 *Jan 4 08:39:23.107: //54/027B929B809D/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x4881E044, Interface Type=3, Destination=, Mode=0x0, Call Params(Calling Number=001111,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=123(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=9, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) *Jan 4 08:39:23.107: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:23.107: :cc_get_feature_vsa malloc success *Jan 4 08:39:23.107: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:23.111: cc_get_feature_vsa count is 2 *Jan 4 08:39:23.111: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:23.111: :FEATURE_VSA attributes are: feature_name:0,feature_time:1254731968,feature_id:55 *Jan 4 08:39:23.111: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_spi_event_handler: Event Category=1, Event=176 *Jan 4 08:39:23.111: //55/027B929B809D/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1 *Jan 4 08:39:23.111: //55/027B929B809D/CCAPI/ccCallSetContext: Context=0x4ACC3B50 *Jan 4 08:39:23.111: //54/027B929B809D/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=9 *Jan 4 08:39:23.111: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_DIGIT_COLLECT, event:E_CC_PROCEEDING] *Jan 4 08:39:23.111: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/act_dcollect_proc: *Jan 4 08:39:23.115: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding *Jan 4 08:39:23.115: [50/0/3.1] set signal state = 0x8 timestamp = 0 *Jan 4 08:39:23.115: edsp_set_sig_state: [50/0/3.1] packet_len=12 channel_id=17 packet_id=39 state=0x8 timestamp=0x0 *Jan 4 08:39:23.115: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:23.115: edsp_soutput: [50/0/3.1] DN=3 chnl=17 EFXS_PROCEEDING *Jan 4 08:39:23.123: //55/027B929B809D/CCAPI/cc_api_call_proceeding: Interface=0x4881E044, Progress Indication=NULL(0) *Jan 4 08:39:23.179: //55/027B929B809D/CCAPI/cc_api_call_disconnected: Cause Value=57, Interface=0x4881E044, Call Id=55 *Jan 4 08:39:23.179: //55/027B929B809D/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=57, Retry Count=0) *Jan 4 08:39:23.179: //54/027B929B809D/CCAPI/ccCallReleaseResources: release reserved xcoding resource. *Jan 4 08:39:23.179: //55/027B929B809D/CCAPI/ccCallSetAAA_Accounting: Accounting=0, Call Id=55 *Jan 4 08:39:23.179: //55/027B929B809D/CCAPI/ccCallDisconnect: Cause Value=57, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=57) *Jan 4 08:39:23.179: //55/027B929B809D/CCAPI/ccCallDisconnect: Cause Value=57, Call Entry(Responsed=TRUE, Cause Value=57) *Jan 4 08:39:23.183: //55/027B929B809D/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x4881E044, Tag=0x0, Call Id=55, Call Entry(Disconnect Cause=57, Voice Class Cause Code=0, Retry Count=0) *Jan 4 08:39:23.183: //55/027B929B809D/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Jan 4 08:39:23.183: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:23.183: :cc_free_feature_vsa freeing 4AC9B0B8 *Jan 4 08:39:23.183: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:23.183: vsacount in free is 1 *Jan 4 08:39:23.187: //54/027B929B809D/CCAPI/ccCallDisconnect: Cause Value=57, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) *Jan 4 08:39:23.187: //54/027B929B809D/CCAPI/ccCallDisconnect: Cause Value=57, Call Entry(Responsed=TRUE, Cause Value=57) *Jan 4 08:39:23.187: //54/027B929B809D/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null *Jan 4 08:39:23.187: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_PROCEEDING, event:E_CC_DISCONNECT] *Jan 4 08:39:23.187: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: Cause Value=57, Hairpin=FALSE *Jan 4 08:39:23.187: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495198 *Jan 4 08:39:23.187: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_ring_noan_timer_stop: Timer Stop Time=495198 *Jan 4 08:39:23.187: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: [Fast Busy]-Tone Played In Direction [Network] *Jan 4 08:39:23.187: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495198 *Jan 4 08:39:23.187: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_play_busy_timer_start: Timer Start Time=495199, Busy Timer Value=30(ms) *Jan 4 08:39:23.191: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: Tone=Fast Busy, Tone Timeout=30(s), Progress Indication Sent=0 *Jan 4 08:39:23.191: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:23.191: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_ON 2 dir 1 *Jan 4 08:39:24.895: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=63664 systime=495369 *Jan 4 08:39:24.895: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_DSP_SIG_0100]efxs_offhook_onhook *Jan 4 08:39:24.895: htsp_timer - 10 msec *Jan 4 08:39:24.907: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_HTSP_EVENT_TIMER]efxs_offhook_timer *Jan 4 08:39:24.907: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_WAIT_RELEASE_NC, event:E_TSP_DISCONNECT_IND] *Jan 4 08:39:24.907: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/act_pre_con_disc_rel: htsp_release_req: cause 57, no_onhook 0 *Jan 4 08:39:24.907: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/act_pre_con_disc_rel: Cause Value=57 *Jan 4 08:39:24.907: htsp_process_event: [50/0/3.1, EFXS_ONHOOK, E_HTSP_RELEASE_REQ]efxs_onhook_release *Jan 4 08:39:24.907: htsp_timer_stop *Jan 4 08:39:24.907: [50/0/3.1] set signal state = 0x4 timestamp = 0 *Jan 4 08:39:24.907: edsp_set_sig_state: [50/0/3.1] packet_len=12 channel_id=17 packet_id=39 state=0x4 timestamp=0x0 *Jan 4 08:39:24.907: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:24.907: edsp_soutput: [50/0/3.1] DN=3 chnl=17 StationRingOff *Jan 4 08:39:24.911: TGRM: reg_invoke_tgrm_call_update(50, 0, 3, 65535, 0, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_IN) *Jan 4 08:39:24.911: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_WAIT_RELEASE_RESP, event:E_TSP_DISCONNECT_CONF] *Jan 4 08:39:24.911: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/act_wrelease_release: *Jan 4 08:39:24.911: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_play_busy_timer_stop: Timer Stop Time=495371 *Jan 4 08:39:24.911: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495371 *Jan 4 08:39:24.911: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_do_call_history: *Jan 4 08:39:24.911: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_do_call_history: Coder Rate=-1 *Jan 4 08:39:24.911: //54/027B929B809D/VTSP:(50/0/3):-1:0:17/vtsp_is_valid_dsm_handle: DMGR=0x492F4528, VTSP CDB=0x4AC3F650 *Jan 4 08:39:24.911: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:24.911: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_OFF *Jan 4 08:39:24.911: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:24.911: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_IDLE_MODE *Jan 4 08:39:24.915: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:24.915: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CLOSE_VOICE_CHANNEL *Jan 4 08:39:24.915: edsprm_close_cleanup *Jan 4 08:39:24.915: //54/027B929B809D/VTSP:(50/0/3):-1:-1:-1/vtsp_dsm_closed_cb: *Jan 4 08:39:24.915: //54/027B929B809D/VTSP:(50/0/3):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] *Jan 4 08:39:24.915: //54/027B929B809D/VTSP:(50/0/3):-1:-1:-1/act_terminate: *Jan 4 08:39:24.915: //54/027B929B809D/VTSP:(50/0/3):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=495371 *Jan 4 08:39:24.915: //54/027B929B809D/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x4A9F3234, Tag=0x0, Call Id=54, Call Entry(Disconnect Cause=57, Voice Class Cause Code=0, Retry Count=0) *Jan 4 08:39:24.915: //54/027B929B809D/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Jan 4 08:39:24.915: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:24.915: :cc_free_feature_vsa freeing 4AC9B198 *Jan 4 08:39:24.915: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:24.915: vsacount in free is 0 *Jan 4 08:39:24.915: //-1/027B929B809D/VTSP:(50/0/3):-1:-1:-1/vtsp_free_cdb: CDB=0x4AC3F650 *Jan 4 08:39:25.983: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=64664 systime=495478 *Jan 4 08:39:25.983: htsp_process_event: [50/0/3.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind *Jan 4 08:39:25.983: [50/0/3.1] get_local_station_id calling num=111 calling name= calling time=01/04 11:39 orig called= *Jan 4 08:39:25.983: TGRM: reg_invoke_tgrm_accept_call(0, TGRM_CALL_VOICE, TGRM_DIRECTION_IN, 50, 0, 3, 65535) *Jan 4 08:39:25.983: TGRM: Calling vtsp_tsp_call_setup_ind(). *Jan 4 08:39:25.987: TGRM: reg_invoke_tgrm_call_update(50, 0, 3, 65535, 0, TGRM_CALL_BUSY, TGRM_CALL_VOICE, TGRM_DIRECTION_IN) *Jan 4 08:39:25.987: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_allocate_cdb: CDB=0x4AC3F378 *Jan 4 08:39:25.987: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_do_call_setup_ind: Event=E_TSP_SETUP_IND Progress Indication=3, CarrierIDCode=, Info Trans Capability=0, Source Carrier ID=, tg_label_flag=0 *Jan 4 08:39:25.987: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_do_call_setup_ind: Calling Number=111, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed CLIR=FALSE, CLID Transparent=FALSE, Null Originating Calling Number=FALSE, Calling Translated=FALSE *Jan 4 08:39:25.987: //-1/06300ECD80A3/VTSP:(50/0/3):-1:-1:-1/vtsp_do_normal_call_setup_ind: *Jan 4 08:39:25.987: //-1/06300ECD80A3/VTSP:(50/0/3):-1:-1:-1/vtsp_timer: Timer Start Time=495478, Timer Value=180000(ms) *Jan 4 08:39:25.987: //-1/06300ECD80A3/VTSP:(50/0/3):-1:-1:-1/vtsp_do_normal_call_setup_ind: IC State Machine (ICSM) Added *Jan 4 08:39:25.987: //-1/06300ECD80A3/VTSP:(50/0/3):-1:-1:-1/vtsp_insert_cdb: *Jan 4 08:39:25.991: //-1/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_opened_cb: *Jan 4 08:39:25.991: //-1/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] *Jan 4 08:39:25.991: //-1/06300ECD80A3/VTSP:(50/0/3):-1:0:17/act_setup_ind_pend_success: *Jan 4 08:39:25.995: //-1/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_realloc_cdb: CDB=0x4AC3F378 *Jan 4 08:39:25.995: //-1/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_indicate_call: *Jan 4 08:39:25.995: //-1/06300ECD80A3/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=111 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 *Jan 4 08:39:25.995: //-1/06300ECD80A3/CCAPI/cc_api_call_setup_ind_common: Interface=0x4A9F3234, Call Info( Calling Number=111,(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=FALSE, Incoming Dial-peer=20003, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1 *Jan 4 08:39:25.995: //-1/06300ECD80A3/CCAPI/ccCheckClipClir: In: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:25.995: //-1/06300ECD80A3/CCAPI/ccCheckClipClir: Out: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:25.995: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:25.995: :cc_get_feature_vsa malloc success *Jan 4 08:39:25.995: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:25.995: cc_get_feature_vsa count is 1 *Jan 4 08:39:25.995: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:25.995: :FEATURE_VSA attributes are: feature_name:0,feature_time:1254732192,feature_id:56 *Jan 4 08:39:25.999: //56/06300ECD80A3/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=(TON=Unknown, NPI=Unknown)) *Jan 4 08:39:25.999: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_insert_cdb: *Jan 4 08:39:25.999: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer: Timer Start Time=495480, Timer Value=180000(ms) *Jan 4 08:39:25.999: //56/06300ECD80A3/CCAPI/cc_process_call_setup_ind: Event=0x4933FF90 *Jan 4 08:39:25.999: //56/06300ECD80A3/CCAPI/ccCallSetContext: Context=0x4ACC5010 *Jan 4 08:39:25.999: //56/06300ECD80A3/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 56 with tag 20003 to app "_ManagedAppProcess_Default" *Jan 4 08:39:26.003: //56/06300ECD80A3/CCAPI/ccCallSetupAck: Call Id=56 *Jan 4 08:39:26.003: //56/06300ECD80A3/CCAPI/cc_api_set_transfer_info: Transfer Number=, Transfer Reason=0x0 *Jan 4 08:39:26.003: //56/06300ECD80A3/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=TRUE, Tone=Dial Tone, Tone Direction=Network, Params=0x0, Call Id=56 *Jan 4 08:39:26.003: //56/06300ECD80A3/CCAPI/ccSetDigitTimeouts: Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms) *Jan 4 08:39:26.003: //56/06300ECD80A3/CCAPI/ccSetDigitTimeouts: Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms)) *Jan 4 08:39:26.003: //56/06300ECD80A3/CCAPI/ccCallReportDigits: (callID=0x38, digit_event=0x1, enable=TRUE, consume=FALSE) *Jan 4 08:39:26.003: //56/06300ECD80A3/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=56 *Jan 4 08:39:26.003: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: *Jan 4 08:39:26.003: //56/06300ECD80A3/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x4A9F3234, callID=0x38, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) *Jan 4 08:39:26.003: //56/06300ECD80A3/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x4A9F3234, Call Id=56 *Jan 4 08:39:26.003: //56/06300ECD80A3/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Jan 4 08:39:26.007: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: Digit Reporting=TRUE *Jan 4 08:39:26.007: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_SETUP_INDICATED, event:E_CC_SETUP_ACK] *Jan 4 08:39:26.007: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/act_setup_ind_ack: Fax Relay=TRUE, Dial Tone=TRUE, Digit Collect=TRUE, Overlap=FALSE, DID=FALSE *Jan 4 08:39:26.007: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495480 *Jan 4 08:39:26.007: htsp_process_event: [50/0/3.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call *Jan 4 08:39:26.007: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.007: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_OPEN_VOICE_CHANNEL *Jan 4 08:39:26.007: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.007: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ENC_CONFIG 2 *Jan 4 08:39:26.007: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.007: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_SET_VOICE_PLAYOUT_DELAY *Jan 4 08:39:26.007: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ECHO_CANCELLER_CONTROL *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_VOICE_MODE codec=1 vad=0 size=160 edsp_state=0 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ECHO_CANCELLER_CONTROL *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_SET_GAINS *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_DTMF_MODE *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.011: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_ON 4 dir 1 *Jan 4 08:39:26.747: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:26.747: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=0 *Jan 4 08:39:26.747: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:26.747: //56/06300ECD80A3/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=0, DigitBeginFlags=0x0, Rtp Timestamp=0x340080, Rtp Expiration=0x0 *Jan 4 08:39:26.747: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.747: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_OFF *Jan 4 08:39:26.747: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:26.747: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495554 *Jan 4 08:39:26.747: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=0 *Jan 4 08:39:26.751: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:26.751: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=0, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:26.751: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:26.751: htsp_digit_ready(50/0/3.1): digit = 0 *Jan 4 08:39:26.751: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:26.751: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_DTMF_MODE *Jan 4 08:39:27.543: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:27.543: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=0 *Jan 4 08:39:27.543: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:27.543: //56/06300ECD80A3/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=0, DigitBeginFlags=0x0, Rtp Timestamp=0x347D80, Rtp Expiration=0x0 *Jan 4 08:39:27.543: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:27.543: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495634 *Jan 4 08:39:27.543: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=0 *Jan 4 08:39:27.543: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:27.543: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=0, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:27.543: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:27.543: htsp_digit_ready(50/0/3.1): digit = 0 *Jan 4 08:39:27.595: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:27.595: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=8 *Jan 4 08:39:27.595: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:27.595: //56/06300ECD80A3/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=8, DigitBeginFlags=0x0, Rtp Timestamp=0x34FA80, Rtp Expiration=0x0 *Jan 4 08:39:27.595: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:27.595: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495639 *Jan 4 08:39:27.595: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=8 *Jan 4 08:39:27.595: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:27.595: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=8, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:27.595: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:27.595: htsp_digit_ready(50/0/3.1): digit = 8 *Jan 4 08:39:28.507: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:28.507: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=1 *Jan 4 08:39:28.507: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:28.507: //56/06300ECD80A3/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=1, DigitBeginFlags=0x0, Rtp Timestamp=0x357780, Rtp Expiration=0x0 *Jan 4 08:39:28.507: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:28.511: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495731 *Jan 4 08:39:28.511: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=1 *Jan 4 08:39:28.511: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:28.511: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=1, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:28.511: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:28.511: htsp_digit_ready(50/0/3.1): digit = 1 *Jan 4 08:39:28.603: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:28.603: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=1 *Jan 4 08:39:28.607: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:28.607: //56/06300ECD80A3/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=1, DigitBeginFlags=0x0, Rtp Timestamp=0x35F480, Rtp Expiration=0x0 *Jan 4 08:39:28.607: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:28.607: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495740 *Jan 4 08:39:28.607: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=1 *Jan 4 08:39:28.607: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:28.607: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=1, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:28.607: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:28.607: htsp_digit_ready(50/0/3.1): digit = 1 *Jan 4 08:39:29.479: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:29.479: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=1 *Jan 4 08:39:29.483: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:29.483: //56/06300ECD80A3/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=1, DigitBeginFlags=0x0, Rtp Timestamp=0x367180, Rtp Expiration=0x0 *Jan 4 08:39:29.483: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:29.483: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=495828 *Jan 4 08:39:29.483: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=1 *Jan 4 08:39:29.483: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:29.483: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=56, Digit=1, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:29.483: //56/06300ECD80A3/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:29.483: htsp_digit_ready(50/0/3.1): digit = 1 *Jan 4 08:39:29.491: //56/06300ECD80A3/CCAPI/ccCallReportDigits: (callID=0x38, digit_event=0x0, enable=FALSE, consume=FALSE) *Jan 4 08:39:29.491: //56/06300ECD80A3/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=56 *Jan 4 08:39:29.495: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: *Jan 4 08:39:29.495: //56/06300ECD80A3/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x4A9F3234, callID=0x38, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE) *Jan 4 08:39:29.495: //56/06300ECD80A3/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x4A9F3234, Call Id=56 *Jan 4 08:39:29.495: //56/06300ECD80A3/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Jan 4 08:39:29.495: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: Digit Reporting=FALSE *Jan 4 08:39:30.195: //56/06300ECD80A3/CCAPI/ccCallProceeding: Progress Indication=NULL(0) *Jan 4 08:39:30.199: //56/06300ECD80A3/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=92, Params=0x4ACC6520, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) *Jan 4 08:39:30.199: //56/06300ECD80A3/CCAPI/ccCheckClipClir: In: Calling Number=001111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:30.199: //56/06300ECD80A3/CCAPI/ccCheckClipClir: Out: Calling Number=001111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:30.199: //56/06300ECD80A3/CCAPI/ccCallSetupRequest: Destination Pattern=008..., Called Number=111, Digit Strip=FALSE *Jan 4 08:39:30.199: //56/06300ECD80A3/CCAPI/ccCallSetupRequest: Calling Number=001111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=111(TON=Unknown, NPI=Unknown), Redirect Number=, Display Info= Account Number=, Final Destination Flag=FALSE, Guid=06300ECD-9324-11E4-80A3-DE5D83C9FAA7, Outgoing Dial-peer=92 *Jan 4 08:39:30.199: //56/06300ECD80A3/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=001111 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=111 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 *Jan 4 08:39:30.203: //56/06300ECD80A3/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x4881E044, Interface Type=3, Destination=, Mode=0x0, Call Params(Calling Number=001111,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=111(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=92, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) *Jan 4 08:39:30.203: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:30.203: :cc_get_feature_vsa malloc success *Jan 4 08:39:30.203: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:30.203: cc_get_feature_vsa count is 2 *Jan 4 08:39:30.203: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:30.203: :FEATURE_VSA attributes are: feature_name:0,feature_time:1254731968,feature_id:57 *Jan 4 08:39:30.203: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_spi_event_handler: Event Category=1, Event=176 *Jan 4 08:39:30.203: //57/06300ECD80A3/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1 *Jan 4 08:39:30.207: //57/06300ECD80A3/CCAPI/ccCallSetContext: Context=0x4ACC64D0 *Jan 4 08:39:30.207: //56/06300ECD80A3/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=92 *Jan 4 08:39:30.207: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_DIGIT_COLLECT, event:E_CC_PROCEEDING] *Jan 4 08:39:30.207: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/act_dcollect_proc: *Jan 4 08:39:30.207: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding *Jan 4 08:39:30.207: [50/0/3.1] set signal state = 0x8 timestamp = 0 *Jan 4 08:39:30.207: edsp_set_sig_state: [50/0/3.1] packet_len=12 channel_id=17 packet_id=39 state=0x8 timestamp=0x0 *Jan 4 08:39:30.207: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:30.207: edsp_soutput: [50/0/3.1] DN=3 chnl=17 EFXS_PROCEEDING *Jan 4 08:39:30.215: //57/06300ECD80A3/CCAPI/cc_api_call_proceeding: Interface=0x4881E044, Progress Indication=NULL(0) *Jan 4 08:39:30.775: //57/06300ECD80A3/CCAPI/cc_api_call_disconnected: Cause Value=57, Interface=0x4881E044, Call Id=57 *Jan 4 08:39:30.775: //57/06300ECD80A3/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=57, Retry Count=0) *Jan 4 08:39:30.779: //56/06300ECD80A3/CCAPI/ccCallReleaseResources: release reserved xcoding resource. *Jan 4 08:39:30.779: //57/06300ECD80A3/CCAPI/ccCallSetAAA_Accounting: Accounting=0, Call Id=57 *Jan 4 08:39:30.779: //57/06300ECD80A3/CCAPI/ccCallDisconnect: Cause Value=57, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=57) *Jan 4 08:39:30.779: //57/06300ECD80A3/CCAPI/ccCallDisconnect: Cause Value=57, Call Entry(Responsed=TRUE, Cause Value=57) *Jan 4 08:39:31.579: //57/06300ECD80A3/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x4881E044, Tag=0x0, Call Id=57, Call Entry(Disconnect Cause=57, Voice Class Cause Code=0, Retry Count=0) *Jan 4 08:39:31.579: //57/06300ECD80A3/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Jan 4 08:39:31.579: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:31.579: :cc_free_feature_vsa freeing 4AC9B0B8 *Jan 4 08:39:31.583: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:31.583: vsacount in free is 1 *Jan 4 08:39:31.587: //56/06300ECD80A3/CCAPI/ccCallDisconnect: Cause Value=57, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) *Jan 4 08:39:31.587: //56/06300ECD80A3/CCAPI/ccCallDisconnect: Cause Value=57, Call Entry(Responsed=TRUE, Cause Value=57) *Jan 4 08:39:31.587: //56/06300ECD80A3/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null *Jan 4 08:39:31.587: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_PROCEEDING, event:E_CC_DISCONNECT] *Jan 4 08:39:31.587: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: Cause Value=57, Hairpin=FALSE *Jan 4 08:39:31.587: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=496038 *Jan 4 08:39:31.587: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_ring_noan_timer_stop: Timer Stop Time=496038 *Jan 4 08:39:31.587: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: [Fast Busy]-Tone Played In Direction [Network] *Jan 4 08:39:31.587: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=496038 *Jan 4 08:39:31.587: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_play_busy_timer_start: Timer Start Time=496038, Busy Timer Value=30(ms) *Jan 4 08:39:31.591: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: Tone=Fast Busy, Tone Timeout=30(s), Progress Indication Sent=0 *Jan 4 08:39:31.611: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:31.611: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_ON 2 dir 1 *Jan 4 08:39:34.055: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=61056 systime=496241 *Jan 4 08:39:34.059: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_DSP_SIG_0100]efxs_offhook_onhook *Jan 4 08:39:34.059: htsp_timer - 10 msec *Jan 4 08:39:34.075: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_HTSP_EVENT_TIMER]efxs_offhook_timer *Jan 4 08:39:34.075: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_WAIT_RELEASE_NC, event:E_TSP_DISCONNECT_IND] *Jan 4 08:39:34.075: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/act_pre_con_disc_rel: htsp_release_req: cause 57, no_onhook 0 *Jan 4 08:39:34.075: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/act_pre_con_disc_rel: Cause Value=57 *Jan 4 08:39:34.075: htsp_process_event: [50/0/3.1, EFXS_ONHOOK, E_HTSP_RELEASE_REQ]efxs_onhook_release *Jan 4 08:39:34.075: htsp_timer_stop *Jan 4 08:39:34.075: [50/0/3.1] set signal state = 0x4 timestamp = 0 *Jan 4 08:39:34.075: edsp_set_sig_state: [50/0/3.1] packet_len=12 channel_id=17 packet_id=39 state=0x4 timestamp=0x0 *Jan 4 08:39:34.075: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:34.075: edsp_soutput: [50/0/3.1] DN=3 chnl=17 StationRingOff *Jan 4 08:39:34.075: TGRM: reg_invoke_tgrm_call_update(50, 0, 3, 65535, 0, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_IN) *Jan 4 08:39:34.079: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_WAIT_RELEASE_RESP, event:E_TSP_DISCONNECT_CONF] *Jan 4 08:39:34.079: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/act_wrelease_release: *Jan 4 08:39:34.079: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_play_busy_timer_stop: Timer Stop Time=496288 *Jan 4 08:39:34.079: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=496288 *Jan 4 08:39:34.079: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_do_call_history: *Jan 4 08:39:34.079: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_do_call_history: Coder Rate=-1 *Jan 4 08:39:34.079: //56/06300ECD80A3/VTSP:(50/0/3):-1:0:17/vtsp_is_valid_dsm_handle: DMGR=0x492F4528, VTSP CDB=0x4AC3F378 *Jan 4 08:39:34.079: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:34.079: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_OFF *Jan 4 08:39:34.079: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:34.079: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_IDLE_MODE *Jan 4 08:39:34.079: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:34.083: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CLOSE_VOICE_CHANNEL *Jan 4 08:39:34.083: edsprm_close_cleanup *Jan 4 08:39:34.083: //56/06300ECD80A3/VTSP:(50/0/3):-1:-1:-1/vtsp_dsm_closed_cb: *Jan 4 08:39:34.083: //56/06300ECD80A3/VTSP:(50/0/3):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] *Jan 4 08:39:34.083: //56/06300ECD80A3/VTSP:(50/0/3):-1:-1:-1/act_terminate: *Jan 4 08:39:34.083: //56/06300ECD80A3/VTSP:(50/0/3):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=496288 *Jan 4 08:39:34.083: //56/06300ECD80A3/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x4A9F3234, Tag=0x0, Call Id=56, Call Entry(Disconnect Cause=57, Voice Class Cause Code=0, Retry Count=0) *Jan 4 08:39:34.083: //56/06300ECD80A3/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Jan 4 08:39:34.083: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:34.083: :cc_free_feature_vsa freeing 4AC9B198 *Jan 4 08:39:34.083: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:34.083: vsacount in free is 0 *Jan 4 08:39:34.083: //-1/06300ECD80A3/VTSP:(50/0/3):-1:-1:-1/vtsp_free_cdb: CDB=0x4AC3F378 *Jan 4 08:39:38.931: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=62056 systime=496773 *Jan 4 08:39:38.931: htsp_process_event: [50/0/3.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind *Jan 4 08:39:38.935: [50/0/3.1] get_local_station_id calling num=111 calling name= calling time=01/04 11:39 orig called= *Jan 4 08:39:38.935: TGRM: reg_invoke_tgrm_accept_call(0, TGRM_CALL_VOICE, TGRM_DIRECTION_IN, 50, 0, 3, 65535) *Jan 4 08:39:38.935: TGRM: Calling vtsp_tsp_call_setup_ind(). *Jan 4 08:39:38.935: TGRM: reg_invoke_tgrm_call_update(50, 0, 3, 65535, 0, TGRM_CALL_BUSY, TGRM_CALL_VOICE, TGRM_DIRECTION_IN) *Jan 4 08:39:38.935: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_allocate_cdb: CDB=0x4AC3F650 *Jan 4 08:39:38.935: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_do_call_setup_ind: Event=E_TSP_SETUP_IND Progress Indication=3, CarrierIDCode=, Info Trans Capability=0, Source Carrier ID=, tg_label_flag=0 *Jan 4 08:39:38.935: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_do_call_setup_ind: Calling Number=111, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed CLIR=FALSE, CLID Transparent=FALSE, Null Originating Calling Number=FALSE, Calling Translated=FALSE *Jan 4 08:39:38.935: //-1/0DE7C41580A9/VTSP:(50/0/3):-1:-1:-1/vtsp_do_normal_call_setup_ind: *Jan 4 08:39:38.935: //-1/0DE7C41580A9/VTSP:(50/0/3):-1:-1:-1/vtsp_timer: Timer Start Time=496773, Timer Value=180000(ms) *Jan 4 08:39:38.935: //-1/0DE7C41580A9/VTSP:(50/0/3):-1:-1:-1/vtsp_do_normal_call_setup_ind: IC State Machine (ICSM) Added *Jan 4 08:39:38.935: //-1/0DE7C41580A9/VTSP:(50/0/3):-1:-1:-1/vtsp_insert_cdb: *Jan 4 08:39:38.939: //-1/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_opened_cb: *Jan 4 08:39:38.943: //-1/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] *Jan 4 08:39:38.943: //-1/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/act_setup_ind_pend_success: *Jan 4 08:39:38.943: //-1/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_realloc_cdb: CDB=0x4AC3F650 *Jan 4 08:39:38.943: //-1/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_indicate_call: *Jan 4 08:39:38.943: //-1/0DE7C41580A9/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=111 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 *Jan 4 08:39:38.943: //-1/0DE7C41580A9/CCAPI/cc_api_call_setup_ind_common: Interface=0x4A9F3234, Call Info( Calling Number=111,(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=FALSE, Incoming Dial-peer=20003, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1 *Jan 4 08:39:38.943: //-1/0DE7C41580A9/CCAPI/ccCheckClipClir: In: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:38.943: //-1/0DE7C41580A9/CCAPI/ccCheckClipClir: Out: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:38.943: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:38.943: :cc_get_feature_vsa malloc success *Jan 4 08:39:38.943: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:38.943: cc_get_feature_vsa count is 1 *Jan 4 08:39:38.947: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:38.947: :FEATURE_VSA attributes are: feature_name:0,feature_time:1254732192,feature_id:58 *Jan 4 08:39:38.947: //58/0DE7C41580A9/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=(TON=Unknown, NPI=Unknown)) *Jan 4 08:39:38.947: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_insert_cdb: *Jan 4 08:39:38.947: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_timer: Timer Start Time=496774, Timer Value=180000(ms) *Jan 4 08:39:38.947: //58/0DE7C41580A9/CCAPI/cc_process_call_setup_ind: Event=0x4933FF90 *Jan 4 08:39:38.947: //58/0DE7C41580A9/CCAPI/ccCallSetContext: Context=0x4ACC7990 *Jan 4 08:39:38.947: //58/0DE7C41580A9/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 58 with tag 20003 to app "_ManagedAppProcess_Default" *Jan 4 08:39:38.951: //58/0DE7C41580A9/CCAPI/ccCallSetupAck: Call Id=58 *Jan 4 08:39:38.951: //58/0DE7C41580A9/CCAPI/cc_api_set_transfer_info: Transfer Number=, Transfer Reason=0x0 *Jan 4 08:39:38.951: //58/0DE7C41580A9/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=TRUE, Tone=Dial Tone, Tone Direction=Network, Params=0x0, Call Id=58 *Jan 4 08:39:38.951: //58/0DE7C41580A9/CCAPI/ccSetDigitTimeouts: Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms) *Jan 4 08:39:38.951: //58/0DE7C41580A9/CCAPI/ccSetDigitTimeouts: Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms)) *Jan 4 08:39:38.951: //58/0DE7C41580A9/CCAPI/ccCallReportDigits: (callID=0x3A, digit_event=0x1, enable=TRUE, consume=FALSE) *Jan 4 08:39:38.951: //58/0DE7C41580A9/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=58 *Jan 4 08:39:38.951: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: *Jan 4 08:39:38.951: //58/0DE7C41580A9/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x4A9F3234, callID=0x3A, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) *Jan 4 08:39:38.951: //58/0DE7C41580A9/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x4A9F3234, Call Id=58 *Jan 4 08:39:38.955: //58/0DE7C41580A9/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Jan 4 08:39:38.955: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: Digit Reporting=TRUE *Jan 4 08:39:38.955: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_SETUP_INDICATED, event:E_CC_SETUP_ACK] *Jan 4 08:39:38.955: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/act_setup_ind_ack: Fax Relay=TRUE, Dial Tone=TRUE, Digit Collect=TRUE, Overlap=FALSE, DID=FALSE *Jan 4 08:39:38.955: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=496775 *Jan 4 08:39:38.955: htsp_process_event: [50/0/3.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call *Jan 4 08:39:38.955: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.955: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_OPEN_VOICE_CHANNEL *Jan 4 08:39:38.955: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.955: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ENC_CONFIG 2 *Jan 4 08:39:38.955: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_SET_VOICE_PLAYOUT_DELAY *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ECHO_CANCELLER_CONTROL *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_VOICE_MODE codec=1 vad=0 size=160 edsp_state=0 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ECHO_CANCELLER_CONTROL *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_SET_GAINS *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_DTMF_MODE *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:38.959: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_ON 4 dir 1 *Jan 4 08:39:39.939: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:39.939: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=1 *Jan 4 08:39:39.939: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:39.939: //58/0DE7C41580A9/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=58, Digit=1, DigitBeginFlags=0x0, Rtp Timestamp=0x36F650, Rtp Expiration=0x0 *Jan 4 08:39:39.939: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:39.939: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_OFF *Jan 4 08:39:39.939: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:39.939: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=496874 *Jan 4 08:39:39.939: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=1 *Jan 4 08:39:39.939: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:39.939: //58/0DE7C41580A9/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=58, Digit=1, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:39.939: //58/0DE7C41580A9/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:39.939: htsp_digit_ready(50/0/3.1): digit = 1 *Jan 4 08:39:39.939: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:39.939: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_DTMF_MODE *Jan 4 08:39:40.199: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:40.199: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=2 *Jan 4 08:39:40.199: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:40.199: //58/0DE7C41580A9/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=58, Digit=2, DigitBeginFlags=0x0, Rtp Timestamp=0x377350, Rtp Expiration=0x0 *Jan 4 08:39:40.199: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:40.199: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=496900 *Jan 4 08:39:40.199: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=2 *Jan 4 08:39:40.199: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:40.199: //58/0DE7C41580A9/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=58, Digit=2, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:40.203: //58/0DE7C41580A9/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:40.203: htsp_digit_ready(50/0/3.1): digit = 2 *Jan 4 08:39:40.651: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:40.651: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=3 *Jan 4 08:39:40.655: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:40.655: //58/0DE7C41580A9/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=58, Digit=3, DigitBeginFlags=0x0, Rtp Timestamp=0x37F050, Rtp Expiration=0x0 *Jan 4 08:39:40.655: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:40.655: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=496945 *Jan 4 08:39:40.655: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=3 *Jan 4 08:39:40.655: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:40.655: //58/0DE7C41580A9/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=58, Digit=3, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:40.655: //58/0DE7C41580A9/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:40.655: htsp_digit_ready(50/0/3.1): digit = 3 *Jan 4 08:39:40.659: //58/0DE7C41580A9/CCAPI/ccCallReportDigits: (callID=0x3A, digit_event=0x0, enable=FALSE, consume=FALSE) *Jan 4 08:39:40.659: //58/0DE7C41580A9/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=58 *Jan 4 08:39:40.659: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: *Jan 4 08:39:40.659: //58/0DE7C41580A9/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x4A9F3234, callID=0x3A, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE) *Jan 4 08:39:40.659: //58/0DE7C41580A9/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x4A9F3234, Call Id=58 *Jan 4 08:39:40.659: //58/0DE7C41580A9/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Jan 4 08:39:40.659: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: Digit Reporting=FALSE *Jan 4 08:39:40.663: //58/0DE7C41580A9/CCAPI/ccCallProceeding: Progress Indication=NULL(0) *Jan 4 08:39:40.667: //58/0DE7C41580A9/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=8, Params=0x4ACC8EA0, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) *Jan 4 08:39:40.667: //58/0DE7C41580A9/CCAPI/ccCheckClipClir: In: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:40.667: //58/0DE7C41580A9/CCAPI/ccCheckClipClir: Out: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:40.667: //58/0DE7C41580A9/CCAPI/ccCallSetupRequest: Destination Pattern=123, Called Number=123, Digit Strip=FALSE *Jan 4 08:39:40.667: //58/0DE7C41580A9/CCAPI/ccCallSetupRequest: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=123(TON=Unknown, NPI=Unknown), Redirect Number=, Display Info= Account Number=, Final Destination Flag=FALSE, Guid=0DE7C415-9324-11E4-80A9-DE5D83C9FAA7, Outgoing Dial-peer=8 *Jan 4 08:39:40.667: //58/0DE7C41580A9/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=111 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=123 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 *Jan 4 08:39:40.671: //58/0DE7C41580A9/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x489F7B58, Interface Type=1, Destination=, Mode=0x0, Call Params(Calling Number=111,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=123(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=8, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) *Jan 4 08:39:40.671: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:40.671: :cc_get_feature_vsa malloc success *Jan 4 08:39:40.671: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:40.671: cc_get_feature_vsa count is 2 *Jan 4 08:39:40.671: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:40.671: :FEATURE_VSA attributes are: feature_name:0,feature_time:1254731968,feature_id:59 *Jan 4 08:39:40.671: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_spi_event_handler: Event Category=1, Event=176 *Jan 4 08:39:40.671: //59/0DE7C41580A9/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=1, FlowMode=1 *Jan 4 08:39:40.671: //59/0DE7C41580A9/CCAPI/ccCallSetContext: Context=0x4ACC8E50 *Jan 4 08:39:40.671: //58/0DE7C41580A9/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=8 *Jan 4 08:39:40.671: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_DIGIT_COLLECT, event:E_CC_PROCEEDING] *Jan 4 08:39:40.671: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/act_dcollect_proc: *Jan 4 08:39:40.671: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding *Jan 4 08:39:40.675: [50/0/3.1] set signal state = 0x8 timestamp = 0 *Jan 4 08:39:40.675: edsp_set_sig_state: [50/0/3.1] packet_len=12 channel_id=17 packet_id=39 state=0x8 timestamp=0x0 *Jan 4 08:39:40.675: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:40.675: edsp_soutput: [50/0/3.1] DN=3 chnl=17 EFXS_PROCEEDING *Jan 4 08:39:41.347: //59/0DE7C41580A9/CCAPI/cc_api_call_disconnected: Cause Value=17, Interface=0x489F7B58, Call Id=59 *Jan 4 08:39:41.347: //59/0DE7C41580A9/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=17, Retry Count=0) *Jan 4 08:39:41.351: //58/0DE7C41580A9/CCAPI/ccCallReleaseResources: release reserved xcoding resource. *Jan 4 08:39:41.351: //59/0DE7C41580A9/CCAPI/ccCallSetAAA_Accounting: Accounting=0, Call Id=59 *Jan 4 08:39:41.351: //59/0DE7C41580A9/CCAPI/ccCallDisconnect: Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=17) *Jan 4 08:39:41.355: //59/0DE7C41580A9/CCAPI/ccCallDisconnect: Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17) *Jan 4 08:39:41.355: //59/0DE7C41580A9/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null *Jan 4 08:39:41.771: //59/0DE7C41580A9/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x489F7B58, Tag=0x0, Call Id=59, Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0) *Jan 4 08:39:41.771: //59/0DE7C41580A9/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Jan 4 08:39:41.771: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:41.771: :cc_free_feature_vsa freeing 4AC9B0B8 *Jan 4 08:39:41.771: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:41.771: vsacount in free is 1 *Jan 4 08:39:41.779: //58/0DE7C41580A9/CCAPI/ccCallDisconnect: Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) *Jan 4 08:39:41.779: //58/0DE7C41580A9/CCAPI/ccCallDisconnect: Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17) *Jan 4 08:39:41.779: //58/0DE7C41580A9/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null *Jan 4 08:39:41.779: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_PROCEEDING, event:E_CC_DISCONNECT] *Jan 4 08:39:41.779: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: Cause Value=17, Hairpin=FALSE *Jan 4 08:39:41.779: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497058 *Jan 4 08:39:41.783: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_ring_noan_timer_stop: Timer Stop Time=497058 *Jan 4 08:39:41.783: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: [Busy]-Tone Played In Direction [Network] *Jan 4 08:39:41.783: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497058 *Jan 4 08:39:41.783: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_play_busy_timer_start: Timer Start Time=497058, Busy Timer Value=30(ms) *Jan 4 08:39:41.783: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: Tone=Busy, Tone Timeout=30(s), Progress Indication Sent=0 *Jan 4 08:39:41.787: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:41.787: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_ON 0 dir 1 *Jan 4 08:39:43.663: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=27984 systime=497246 *Jan 4 08:39:43.663: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_DSP_SIG_0100]efxs_offhook_onhook *Jan 4 08:39:43.663: htsp_timer - 10 msec *Jan 4 08:39:43.675: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_HTSP_EVENT_TIMER]efxs_offhook_timer *Jan 4 08:39:43.675: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_WAIT_RELEASE_NC, event:E_TSP_DISCONNECT_IND] *Jan 4 08:39:43.675: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/act_pre_con_disc_rel: htsp_release_req: cause 17, no_onhook 0 *Jan 4 08:39:43.675: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/act_pre_con_disc_rel: Cause Value=17 *Jan 4 08:39:43.675: htsp_process_event: [50/0/3.1, EFXS_ONHOOK, E_HTSP_RELEASE_REQ]efxs_onhook_release *Jan 4 08:39:43.675: htsp_timer_stop *Jan 4 08:39:43.679: [50/0/3.1] set signal state = 0x4 timestamp = 0 *Jan 4 08:39:43.679: edsp_set_sig_state: [50/0/3.1] packet_len=12 channel_id=17 packet_id=39 state=0x4 timestamp=0x0 *Jan 4 08:39:43.679: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:43.679: edsp_soutput: [50/0/3.1] DN=3 chnl=17 StationRingOff *Jan 4 08:39:43.679: TGRM: reg_invoke_tgrm_call_update(50, 0, 3, 65535, 0, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_IN) *Jan 4 08:39:43.679: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_WAIT_RELEASE_RESP, event:E_TSP_DISCONNECT_CONF] *Jan 4 08:39:43.679: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/act_wrelease_release: *Jan 4 08:39:43.679: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_play_busy_timer_stop: Timer Stop Time=497248 *Jan 4 08:39:43.679: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497248 *Jan 4 08:39:43.679: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_do_call_history: *Jan 4 08:39:43.679: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_do_call_history: Coder Rate=-1 *Jan 4 08:39:43.679: //58/0DE7C41580A9/VTSP:(50/0/3):-1:0:17/vtsp_is_valid_dsm_handle: DMGR=0x492F4528, VTSP CDB=0x4AC3F650 *Jan 4 08:39:43.679: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:43.679: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_OFF *Jan 4 08:39:43.683: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:43.683: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_IDLE_MODE *Jan 4 08:39:43.683: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:43.683: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CLOSE_VOICE_CHANNEL *Jan 4 08:39:43.683: edsprm_close_cleanup *Jan 4 08:39:43.683: //58/0DE7C41580A9/VTSP:(50/0/3):-1:-1:-1/vtsp_dsm_closed_cb: *Jan 4 08:39:43.683: //58/0DE7C41580A9/VTSP:(50/0/3):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] *Jan 4 08:39:43.683: //58/0DE7C41580A9/VTSP:(50/0/3):-1:-1:-1/act_terminate: *Jan 4 08:39:43.683: //58/0DE7C41580A9/VTSP:(50/0/3):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=497248 *Jan 4 08:39:43.683: //58/0DE7C41580A9/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x4A9F3234, Tag=0x0, Call Id=58, Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0) *Jan 4 08:39:43.683: //58/0DE7C41580A9/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Jan 4 08:39:43.683: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:43.683: :cc_free_feature_vsa freeing 4AC9B198 *Jan 4 08:39:43.683: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:43.683: vsacount in free is 0 *Jan 4 08:39:43.687: //-1/0DE7C41580A9/VTSP:(50/0/3):-1:-1:-1/vtsp_free_cdb: CDB=0x4AC3F650 *Jan 4 08:39:46.683: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=28984 systime=497548 *Jan 4 08:39:46.687: htsp_process_event: [50/0/3.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind *Jan 4 08:39:46.687: [50/0/3.1] get_local_station_id calling num=111 calling name= calling time=01/04 11:39 orig called= *Jan 4 08:39:46.687: TGRM: reg_invoke_tgrm_accept_call(0, TGRM_CALL_VOICE, TGRM_DIRECTION_IN, 50, 0, 3, 65535) *Jan 4 08:39:46.687: TGRM: Calling vtsp_tsp_call_setup_ind(). *Jan 4 08:39:46.687: TGRM: reg_invoke_tgrm_call_update(50, 0, 3, 65535, 0, TGRM_CALL_BUSY, TGRM_CALL_VOICE, TGRM_DIRECTION_IN) *Jan 4 08:39:46.687: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_allocate_cdb: CDB=0x4AC3F378 *Jan 4 08:39:46.687: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_do_call_setup_ind: Event=E_TSP_SETUP_IND Progress Indication=3, CarrierIDCode=, Info Trans Capability=0, Source Carrier ID=, tg_label_flag=0 *Jan 4 08:39:46.687: //-1/xxxxxxxxxxxx/VTSP:(50/0/3):-1:-1:-1/vtsp_do_call_setup_ind: Calling Number=111, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed CLIR=FALSE, CLID Transparent=FALSE, Null Originating Calling Number=FALSE, Calling Translated=FALSE *Jan 4 08:39:46.687: //-1/1286A10A80AE/VTSP:(50/0/3):-1:-1:-1/vtsp_do_normal_call_setup_ind: *Jan 4 08:39:46.687: //-1/1286A10A80AE/VTSP:(50/0/3):-1:-1:-1/vtsp_timer: Timer Start Time=497548, Timer Value=180000(ms) *Jan 4 08:39:46.687: //-1/1286A10A80AE/VTSP:(50/0/3):-1:-1:-1/vtsp_do_normal_call_setup_ind: IC State Machine (ICSM) Added *Jan 4 08:39:46.691: //-1/1286A10A80AE/VTSP:(50/0/3):-1:-1:-1/vtsp_insert_cdb: *Jan 4 08:39:46.695: //-1/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_opened_cb: *Jan 4 08:39:46.695: //-1/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] *Jan 4 08:39:46.695: //-1/1286A10A80AE/VTSP:(50/0/3):-1:0:17/act_setup_ind_pend_success: *Jan 4 08:39:46.695: //-1/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_realloc_cdb: CDB=0x4AC3F378 *Jan 4 08:39:46.695: //-1/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_indicate_call: *Jan 4 08:39:46.695: //-1/1286A10A80AE/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=111 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 *Jan 4 08:39:46.695: //-1/1286A10A80AE/CCAPI/cc_api_call_setup_ind_common: Interface=0x4A9F3234, Call Info( Calling Number=111,(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=FALSE, Incoming Dial-peer=20003, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1 *Jan 4 08:39:46.695: //-1/1286A10A80AE/CCAPI/ccCheckClipClir: In: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:46.695: //-1/1286A10A80AE/CCAPI/ccCheckClipClir: Out: Calling Number=111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:46.699: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:46.699: :cc_get_feature_vsa malloc success *Jan 4 08:39:46.699: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:46.699: cc_get_feature_vsa count is 1 *Jan 4 08:39:46.699: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:46.699: :FEATURE_VSA attributes are: feature_name:0,feature_time:1254732192,feature_id:60 *Jan 4 08:39:46.699: //60/1286A10A80AE/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=(TON=Unknown, NPI=Unknown)) *Jan 4 08:39:46.699: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_insert_cdb: *Jan 4 08:39:46.699: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer: Timer Start Time=497550, Timer Value=180000(ms) *Jan 4 08:39:46.703: //60/1286A10A80AE/CCAPI/cc_process_call_setup_ind: Event=0x49340A88 *Jan 4 08:39:46.703: //60/1286A10A80AE/CCAPI/ccCallSetContext: Context=0x4ACCA310 *Jan 4 08:39:46.703: //60/1286A10A80AE/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 60 with tag 20003 to app "_ManagedAppProcess_Default" *Jan 4 08:39:46.703: //60/1286A10A80AE/CCAPI/ccCallSetupAck: Call Id=60 *Jan 4 08:39:46.703: //60/1286A10A80AE/CCAPI/cc_api_set_transfer_info: Transfer Number=, Transfer Reason=0x0 *Jan 4 08:39:46.703: //60/1286A10A80AE/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=TRUE, Tone=Dial Tone, Tone Direction=Network, Params=0x0, Call Id=60 *Jan 4 08:39:46.707: //60/1286A10A80AE/CCAPI/ccSetDigitTimeouts: Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms) *Jan 4 08:39:46.707: //60/1286A10A80AE/CCAPI/ccSetDigitTimeouts: Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms)) *Jan 4 08:39:46.707: //60/1286A10A80AE/CCAPI/ccCallReportDigits: (callID=0x3C, digit_event=0x1, enable=TRUE, consume=FALSE) *Jan 4 08:39:46.707: //60/1286A10A80AE/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=60 *Jan 4 08:39:46.707: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: *Jan 4 08:39:46.707: //60/1286A10A80AE/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x4A9F3234, callID=0x3C, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) *Jan 4 08:39:46.707: //60/1286A10A80AE/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x4A9F3234, Call Id=60 *Jan 4 08:39:46.707: //60/1286A10A80AE/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Jan 4 08:39:46.707: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: Digit Reporting=TRUE *Jan 4 08:39:46.707: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_SETUP_INDICATED, event:E_CC_SETUP_ACK] *Jan 4 08:39:46.707: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/act_setup_ind_ack: Fax Relay=TRUE, Dial Tone=TRUE, Digit Collect=TRUE, Overlap=FALSE, DID=FALSE *Jan 4 08:39:46.707: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497550 *Jan 4 08:39:46.711: htsp_process_event: [50/0/3.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_OPEN_VOICE_CHANNEL *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ENC_CONFIG 2 *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_SET_VOICE_PLAYOUT_DELAY *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ECHO_CANCELLER_CONTROL *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_VOICE_MODE codec=1 vad=0 size=160 edsp_state=0 *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_ECHO_CANCELLER_CONTROL *Jan 4 08:39:46.711: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.715: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_SET_GAINS *Jan 4 08:39:46.715: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.715: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.715: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.715: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.715: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_DTMF_MODE *Jan 4 08:39:46.715: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:46.715: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_ON 4 dir 1 *Jan 4 08:39:47.439: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:47.439: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=0 *Jan 4 08:39:47.439: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:47.439: //60/1286A10A80AE/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=0, DigitBeginFlags=0x0, Rtp Timestamp=0x387520, Rtp Expiration=0x0 *Jan 4 08:39:47.439: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:47.439: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_OFF *Jan 4 08:39:47.439: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:47.439: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497624 *Jan 4 08:39:47.439: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=0 *Jan 4 08:39:47.439: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:47.439: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=0, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:47.439: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:47.443: htsp_digit_ready(50/0/3.1): digit = 0 *Jan 4 08:39:47.443: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:47.443: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_DTMF_MODE *Jan 4 08:39:47.863: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:47.863: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=0 *Jan 4 08:39:47.863: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:47.863: //60/1286A10A80AE/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=0, DigitBeginFlags=0x0, Rtp Timestamp=0x38F220, Rtp Expiration=0x0 *Jan 4 08:39:47.863: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:47.863: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497666 *Jan 4 08:39:47.863: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=0 *Jan 4 08:39:47.867: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:47.867: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=0, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:47.867: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:47.867: htsp_digit_ready(50/0/3.1): digit = 0 *Jan 4 08:39:48.091: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:48.091: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=4 *Jan 4 08:39:48.091: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:48.091: //60/1286A10A80AE/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=4, DigitBeginFlags=0x0, Rtp Timestamp=0x396F20, Rtp Expiration=0x0 *Jan 4 08:39:48.091: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:48.091: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497689 *Jan 4 08:39:48.091: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=4 *Jan 4 08:39:48.095: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:48.095: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=4, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:48.095: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:48.095: htsp_digit_ready(50/0/3.1): digit = 4 *Jan 4 08:39:48.527: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:48.527: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=1 *Jan 4 08:39:48.527: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:48.527: //60/1286A10A80AE/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=1, DigitBeginFlags=0x0, Rtp Timestamp=0x39EC20, Rtp Expiration=0x0 *Jan 4 08:39:48.527: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:48.527: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497732 *Jan 4 08:39:48.527: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=1 *Jan 4 08:39:48.527: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:48.527: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=1, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:48.531: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:48.531: htsp_digit_ready(50/0/3.1): digit = 1 *Jan 4 08:39:49.411: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:49.411: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=2 *Jan 4 08:39:49.411: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:49.411: //60/1286A10A80AE/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=2, DigitBeginFlags=0x0, Rtp Timestamp=0x3A6920, Rtp Expiration=0x0 *Jan 4 08:39:49.411: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:49.411: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497821 *Jan 4 08:39:49.411: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=2 *Jan 4 08:39:49.411: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:49.411: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=2, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:49.411: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:49.411: htsp_digit_ready(50/0/3.1): digit = 2 *Jan 4 08:39:49.427: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: *Jan 4 08:39:49.427: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Begin Digit=3 *Jan 4 08:39:49.427: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:49.427: //60/1286A10A80AE/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=3, DigitBeginFlags=0x0, Rtp Timestamp=0x3AE620, Rtp Expiration=0x0 *Jan 4 08:39:49.427: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: *Jan 4 08:39:49.427: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497822 *Jan 4 08:39:49.427: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: End Digit=3 *Jan 4 08:39:49.427: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE *Jan 4 08:39:49.427: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1, Source Call Id=60, Digit=3, Duration=100, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Jan 4 08:39:49.427: //60/1286A10A80AE/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Jan 4 08:39:49.427: htsp_digit_ready(50/0/3.1): digit = 3 *Jan 4 08:39:49.431: //60/1286A10A80AE/CCAPI/ccCallReportDigits: (callID=0x3C, digit_event=0x0, enable=FALSE, consume=FALSE) *Jan 4 08:39:49.431: //60/1286A10A80AE/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=60 *Jan 4 08:39:49.431: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: *Jan 4 08:39:49.431: //60/1286A10A80AE/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x4A9F3234, callID=0x3C, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE) *Jan 4 08:39:49.431: //60/1286A10A80AE/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x4A9F3234, Call Id=60 *Jan 4 08:39:49.431: //60/1286A10A80AE/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Jan 4 08:39:49.431: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_report_digit_control: Digit Reporting=FALSE *Jan 4 08:39:49.435: //60/1286A10A80AE/CCAPI/ccCallProceeding: Progress Indication=NULL(0) *Jan 4 08:39:49.439: //60/1286A10A80AE/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=91, Params=0x4ACB1920, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) *Jan 4 08:39:49.439: //60/1286A10A80AE/CCAPI/ccCheckClipClir: In: Calling Number=001111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:49.439: //60/1286A10A80AE/CCAPI/ccCheckClipClir: Out: Calling Number=001111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 4 08:39:49.439: //60/1286A10A80AE/CCAPI/ccCallSetupRequest: Destination Pattern=004..., Called Number=123, Digit Strip=FALSE *Jan 4 08:39:49.439: //60/1286A10A80AE/CCAPI/ccCallSetupRequest: Calling Number=001111(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=123(TON=Unknown, NPI=Unknown), Redirect Number=, Display Info= Account Number=, Final Destination Flag=FALSE, Guid=1286A10A-9324-11E4-80AE-DE5D83C9FAA7, Outgoing Dial-peer=91 *Jan 4 08:39:49.439: //60/1286A10A80AE/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=001111 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=123 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 *Jan 4 08:39:49.443: //60/1286A10A80AE/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x4881E044, Interface Type=3, Destination=, Mode=0x0, Call Params(Calling Number=001111,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=123(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=91, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) *Jan 4 08:39:49.443: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:49.443: :cc_get_feature_vsa malloc success *Jan 4 08:39:49.443: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:49.443: cc_get_feature_vsa count is 2 *Jan 4 08:39:49.443: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Jan 4 08:39:49.443: :FEATURE_VSA attributes are: feature_name:0,feature_time:1254731968,feature_id:61 *Jan 4 08:39:49.443: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_spi_event_handler: Event Category=1, Event=176 *Jan 4 08:39:49.443: //61/1286A10A80AE/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1 *Jan 4 08:39:49.443: //61/1286A10A80AE/CCAPI/ccCallSetContext: Context=0x4ACB18D0 *Jan 4 08:39:49.443: //60/1286A10A80AE/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=91 *Jan 4 08:39:49.447: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_DIGIT_COLLECT, event:E_CC_PROCEEDING] *Jan 4 08:39:49.447: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/act_dcollect_proc: *Jan 4 08:39:49.447: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding *Jan 4 08:39:49.447: [50/0/3.1] set signal state = 0x8 timestamp = 0 *Jan 4 08:39:49.447: edsp_set_sig_state: [50/0/3.1] packet_len=12 channel_id=17 packet_id=39 state=0x8 timestamp=0x0 *Jan 4 08:39:49.447: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:49.447: edsp_soutput: [50/0/3.1] DN=3 chnl=17 EFXS_PROCEEDING *Jan 4 08:39:49.455: //61/1286A10A80AE/CCAPI/cc_api_call_proceeding: Interface=0x4881E044, Progress Indication=NULL(0) *Jan 4 08:39:49.947: //61/1286A10A80AE/CCAPI/cc_api_call_disconnected: Cause Value=57, Interface=0x4881E044, Call Id=61 *Jan 4 08:39:49.947: //61/1286A10A80AE/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=57, Retry Count=0) *Jan 4 08:39:49.947: //60/1286A10A80AE/CCAPI/ccCallReleaseResources: release reserved xcoding resource. *Jan 4 08:39:49.947: //61/1286A10A80AE/CCAPI/ccCallSetAAA_Accounting: Accounting=0, Call Id=61 *Jan 4 08:39:49.951: //61/1286A10A80AE/CCAPI/ccCallDisconnect: Cause Value=57, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=57) *Jan 4 08:39:49.951: //61/1286A10A80AE/CCAPI/ccCallDisconnect: Cause Value=57, Call Entry(Responsed=TRUE, Cause Value=57) *Jan 4 08:39:49.955: //61/1286A10A80AE/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x4881E044, Tag=0x0, Call Id=61, Call Entry(Disconnect Cause=57, Voice Class Cause Code=0, Retry Count=0) *Jan 4 08:39:49.955: //61/1286A10A80AE/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Jan 4 08:39:49.955: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:49.955: :cc_free_feature_vsa freeing 4AC9B0B8 *Jan 4 08:39:49.955: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:49.955: vsacount in free is 1 *Jan 4 08:39:49.959: //60/1286A10A80AE/CCAPI/ccCallDisconnect: Cause Value=57, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) *Jan 4 08:39:49.959: //60/1286A10A80AE/CCAPI/ccCallDisconnect: Cause Value=57, Call Entry(Responsed=TRUE, Cause Value=57) *Jan 4 08:39:49.959: //60/1286A10A80AE/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null *Jan 4 08:39:49.959: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_PROCEEDING, event:E_CC_DISCONNECT] *Jan 4 08:39:49.959: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: Cause Value=57, Hairpin=FALSE *Jan 4 08:39:49.959: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497876 *Jan 4 08:39:49.959: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_ring_noan_timer_stop: Timer Stop Time=497876 *Jan 4 08:39:49.963: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: [Fast Busy]-Tone Played In Direction [Network] *Jan 4 08:39:49.963: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=497876 *Jan 4 08:39:49.963: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_play_busy_timer_start: Timer Start Time=497876, Busy Timer Value=30(ms) *Jan 4 08:39:49.963: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/act_pre_con_disconnect: Tone=Fast Busy, Tone Timeout=30(s), Progress Indication Sent=0 *Jan 4 08:39:49.963: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:49.963: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_ON 2 dir 1 *Jan 4 08:39:51.683: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=25376 systime=498048 *Jan 4 08:39:51.687: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_DSP_SIG_0100]efxs_offhook_onhook *Jan 4 08:39:51.687: htsp_timer - 10 msec *Jan 4 08:39:51.699: htsp_process_event: [50/0/3.1, EFXS_OFFHOOK, E_HTSP_EVENT_TIMER]efxs_offhook_timer *Jan 4 08:39:51.699: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_WAIT_RELEASE_NC, event:E_TSP_DISCONNECT_IND] *Jan 4 08:39:51.699: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/act_pre_con_disc_rel: htsp_release_req: cause 57, no_onhook 0 *Jan 4 08:39:51.699: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/act_pre_con_disc_rel: Cause Value=57 *Jan 4 08:39:51.699: htsp_process_event: [50/0/3.1, EFXS_ONHOOK, E_HTSP_RELEASE_REQ]efxs_onhook_release *Jan 4 08:39:51.699: htsp_timer_stop *Jan 4 08:39:51.699: [50/0/3.1] set signal state = 0x4 timestamp = 0 *Jan 4 08:39:51.699: edsp_set_sig_state: [50/0/3.1] packet_len=12 channel_id=17 packet_id=39 state=0x4 timestamp=0x0 *Jan 4 08:39:51.699: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:51.699: edsp_soutput: [50/0/3.1] DN=3 chnl=17 StationRingOff *Jan 4 08:39:51.699: TGRM: reg_invoke_tgrm_call_update(50, 0, 3, 65535, 0, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_IN) *Jan 4 08:39:51.703: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_process_event: [state:S_WAIT_RELEASE_RESP, event:E_TSP_DISCONNECT_CONF] *Jan 4 08:39:51.703: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/act_wrelease_release: *Jan 4 08:39:51.703: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_play_busy_timer_stop: Timer Stop Time=498050 *Jan 4 08:39:51.703: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_timer_stop: Timer Stop Time=498050 *Jan 4 08:39:51.703: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_do_call_history: *Jan 4 08:39:51.703: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_do_call_history: Coder Rate=-1 *Jan 4 08:39:51.703: //60/1286A10A80AE/VTSP:(50/0/3):-1:0:17/vtsp_is_valid_dsm_handle: DMGR=0x492F4528, VTSP CDB=0x4AC3F378 *Jan 4 08:39:51.703: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:51.703: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CP_TONE_OFF *Jan 4 08:39:51.703: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:51.703: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_IDLE_MODE *Jan 4 08:39:51.707: edsp_soutput: [50/0/3.1] DN=3 chnl=17 *Jan 4 08:39:51.707: edsp_soutput: [50/0/3.1] DN=3 chnl=17 MSG_RX_CLOSE_VOICE_CHANNEL *Jan 4 08:39:51.707: edsprm_close_cleanup *Jan 4 08:39:51.707: //60/1286A10A80AE/VTSP:(50/0/3):-1:-1:-1/vtsp_dsm_closed_cb: *Jan 4 08:39:51.707: //60/1286A10A80AE/VTSP:(50/0/3):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] *Jan 4 08:39:51.707: //60/1286A10A80AE/VTSP:(50/0/3):-1:-1:-1/act_terminate: *Jan 4 08:39:51.707: //60/1286A10A80AE/VTSP:(50/0/3):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=498050 *Jan 4 08:39:51.707: //60/1286A10A80AE/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x4A9F3234, Tag=0x0, Call Id=60, Call Entry(Disconnect Cause=57, Voice Class Cause Code=0, Retry Count=0) *Jan 4 08:39:51.707: //60/1286A10A80AE/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Jan 4 08:39:51.707: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:51.707: :cc_free_feature_vsa freeing 4AC9B198 *Jan 4 08:39:51.707: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Jan 4 08:39:51.707: vsacount in free is 0 *Jan 4 08:39:51.707: //-1/1286A10A80AE/VTSP:(50/0/3):-1:-1:-1/vtsp_free_cdb: CDB=0x4AC3F378 SFDHQ# SFDHQ# SFDHQ# SFDHQ# SFDHQ# SFDHQ#wr Building configuration... [OK] SFDHQ#