 | Thanks for the replies. I agree this should work without voice-hunt or translation. This used to work before I changed my voice ports around. Here is the output from the debug...caller id and phone numbers have been changed...
*Jan 2 16:06:48.572: htsp_process_event: [1/1/0, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing *Jan 2 16:06:48.572: htsp_timer - 125 msec *Jan 2 16:06:48.700: htsp_process_event: [1/1/0, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer *Jan 2 16:06:48.700: htsp_timer - 10000 msec *Jan 2 16:06:48.700: htsp_timer3 - 5600 msec *Jan 2 16:06:48.700: [1/1/0] htsp_start_caller_id_rx:BELLCORE *Jan 2 16:06:48.704: [1/1/0] htsp_dsm_create_success returns 1 *Jan 2 16:06:50.552: htsp_process_event: [1/1/0, FXOLS_RINGING, E_DSP_SIG_0100] *Jan 2 16:06:50.552: fxols_ringing_not *Jan 2 16:06:50.552: htsp_timer_stop *Jan 2 16:06:50.552: htsp_timer - 10000 msec *Jan 2 16:06:51.996: [1/1/0] htsp_dsm_feature_notify_cb returns 2 id=DSM_FEATURE_SM_CALLERID_RX *Jan 2 16:06:51.996: htsp_process_event: [1/1/0, FXOLS_RINGING, E_HTSP_CALLERID_RX_DONE] *Jan 2 16:06:51.996: htsp_timer_stop *Jan 2 16:06:51.996: [1/1/0] htsp_stop_caller_id_rx. message length 35htsp_setup_ind *Jan 2 16:06:52.000: [1/1/0] get_fxo_caller_id:Caller ID received. Message type=128 length=35 checksum=71 *Jan 2 16:06:52.000: [1/1/0] Caller ID String XX XX XX XX XX XX *Jan 2 16:06:52.004: [1/1/0] get_fxo_caller_id calling num=1234567890 calling name=BOB SNOW calling time=01/02 10:15 *Jan 2 16:06:52.004: fxols_callerid_done: call being answered *Jan 2 16:06:52.004: //-1/xxxxxxxxxxxx/VTSP:(1/1/0):-1:-1:-1/vtsp_allocate_cdb: CDB=0x856AE7F0 *Jan 2 16:06:52.008: //-1/xxxxxxxxxxxx/VTSP:(1/1/0):-1:-1:-1/vtsp_do_call_setup_ind: Event=E_TSP_SETUP_IND Progress Indication=3, CarrierIDCode=, Info Trans Capability=144, Source Carrier ID=, tg_label_flag=0 *Jan 2 16:06:52.008: //-1/xxxxxxxxxxxx/VTSP:(1/1/0):-1:-1:-1/vtsp_do_call_setup_ind: Calling Number=1234567890, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed CLIR=FALSE, CLID Transparent=FALSE, Null Originating Calling Number=FALSE, Calling Translated=FALSE *Jan 2 16:06:52.008: //-1/9E1836A8803B/VTSP:(1/1/0):-1:-1:-1/vtsp_do_normal_call_setup_ind: *Jan 2 16:06:52.012: //-1/9E1836A8803B/VTSP:(1/1/0):-1:-1:-1/vtsp_timer: Timer Start Time=5494334, Timer Value=180000(ms) *Jan 2 16:06:52.012: //-1/9E1836A8803B/VTSP:(1/1/0):-1:-1:-1/vtsp_do_normal_call_setup_ind: IC State Machine (ICSM) Added *Jan 2 16:06:52.012: //-1/9E1836A8803B/VTSP:(1/1/0):-1:-1:-1/vtsp_insert_cdb: *Jan 2 16:06:52.024: //-1/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_dsm_opened_cb: *Jan 2 16:06:52.024: //-1/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_process_event: [state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] *Jan 2 16:06:52.024: //-1/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_setup_ind_pend_success: *Jan 2 16:06:52.024: //-1/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_realloc_cdb: CDB=0x856AE7F0 *Jan 2 16:06:52.028: //-1/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_indicate_call: *Jan 2 16:06:52.028: //-1/9E1836A8803B/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=1234567890 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=2345678901 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 2 16:06:52.032: //-1/9E1836A8803B/CCAPI/cc_api_call_setup_ind_common: Interface=0x84F4A87C, Call Info( Calling Number=1234567890,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=2345678901(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Incoming Dial-peer=5, 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 2 16:06:52.032: //-1/9E1836A8803B/CCAPI/ccCheckClipClir: In: Calling Number=1234567890(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 2 16:06:52.032: //-1/9E1836A8803B/CCAPI/ccCheckClipClir: Out: Calling Number=1234567890(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 2 16:06:52.036: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Jan 2 16:06:52.036: :cc_get_feature_vsa malloc success *Jan 2 16:06:52.036: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Jan 2 16:06:52.036: cc_get_feature_vsa count is 1 *Jan 2 16:06:52.036: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Jan 2 16:06:52.036: :FEATURE_VSA attributes are: feature_name:0,feature_time:2239036760,feature_id:22 *Jan 2 16:06:52.040: //22/9E1836A8803B/CCAPI/cc_api_call_setup_ind_common: Set Up Event Sent; Call Info(Calling Number=1234567890(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=2345678901(TON=Unknown, NPI=Unknown)) *Jan 2 16:06:52.040: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_insert_cdb: *Jan 2 16:06:52.040: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_timer: Timer Start Time=5494337, Timer Value=180000(ms) *Jan 2 16:06:52.044: htsp_process_event: [1/1/0, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK] *Jan 2 16:06:52.044: fxols_wait_setup_ack: *Jan 2 16:06:52.044: htsp_timer - 6000 msec *Jan 2 16:06:52.044: //22/9E1836A8803B/CCAPI/cc_process_call_setup_ind: Event=0x8575CBB0 *Jan 2 16:06:52.044: //22/9E1836A8803B/CCAPI/ccCallSetContext: Context=0x8477FDDC *Jan 2 16:06:52.048: //22/9E1836A8803B/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 22 with tag 5 to app "_ManagedAppProcess_Default" *Jan 2 16:06:52.048: //22/9E1836A8803B/CCAPI/ccCallProceeding: Progress Indication=NULL(0) *Jan 2 16:06:52.060: //22/9E1836A8803B/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=20004, Params=0x84780CA4, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) *Jan 2 16:06:52.060: //22/9E1836A8803B/CCAPI/ccCheckClipClir: In: Calling Number=1234567890(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 2 16:06:52.060: //22/9E1836A8803B/CCAPI/ccCheckClipClir: Out: Calling Number=1234567890(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Jan 2 16:06:52.060: //22/9E1836A8803B/CCAPI/ccCallSetupRequest: Destination Pattern=2345678901$, Called Number=2345678901, Digit Strip=TRUE *Jan 2 16:06:52.060: //22/9E1836A8803B/CCAPI/ccCallSetupRequest: Calling Number=1234567890(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=2345678901(TON=Unknown, NPI=Unknown), Redirect Number=, Display Info=BOB SNOW Account Number=, Final Destination Flag=TRUE, Guid=9E1836A8-3492-11E1-803B-F39EE6EB952D, Outgoing Dial-peer=20004 *Jan 2 16:06:52.064: //22/9E1836A8803B/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=1234567890 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=2345678901 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 2 16:06:52.064: //22/9E1836A8803B/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x859B9750, Interface Type=6, Destination=, Mode=0x0, Call Params(Calling Number=1234567890,(Calling Name=BOB SNOW)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=2345678901(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=20004, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) *Jan 2 16:06:52.068: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Jan 2 16:06:52.068: :cc_get_feature_vsa malloc success *Jan 2 16:06:52.068: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Jan 2 16:06:52.068: cc_get_feature_vsa count is 2 *Jan 2 16:06:52.068: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Jan 2 16:06:52.068: :FEATURE_VSA attributes are: feature_name:0,feature_time:2239036544,feature_id:23 *Jan 2 16:06:52.072: //-1/xxxxxxxxxxxx/VTSP:(50/0/4):-1:-1:-1/vtsp_allocate_cdb: CDB=0x856AEAB0 *Jan 2 16:06:52.072: //-1/xxxxxxxxxxxx/VTSP:(50/0/4):-1:-1:-1/vtsp_insert_cdb: *Jan 2 16:06:52.072: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_spi_event_handler: Event Category=1, Event=148 *Jan 2 16:06:52.072: //23/9E1836A8803B/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 *Jan 2 16:06:52.072: //23/9E1836A8803B/CCAPI/ccCallSetContext: Context=0x84780C54 *Jan 2 16:06:52.072: //22/9E1836A8803B/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=20004 *Jan 2 16:06:52.076: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_process_event: [state:S_SETUP_INDICATED, event:E_CC_PROCEEDING] *Jan 2 16:06:52.076: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_proceeding: Progress Indication=0 *Jan 2 16:06:52.076: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_timer_stop: Timer Stop Time=5494341 *Jan 2 16:06:52.076: //-1/xxxxxxxxxxxx/VTSP:(50/0/4):-1:-1:-1/vtsp_process_event: [state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ] *Jan 2 16:06:52.080: htsp_timer_stop3 *Jan 2 16:06:52.080: //23/9E1836A8803B/VTSP:(50/0/4):-1:-1:-1/vtsp_og_call_req: Echo Cancel=TRUE, Gain Control=0 Auto Gain Control=FALSE, Dual Tone Detect Mask=0x0, Seq Tone Detect=FALSE *Jan 2 16:06:52.080: //23/9E1836A8803B/VTSP:(50/0/4):-1:-1:-1/vtsp_do_call_setup_req: *Jan 2 16:06:52.084: htsp_process_event: [1/1/0, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc *Jan 2 16:06:52.084: [1/1/0] htsp_dsm_close_done *Jan 2 16:06:52.088: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_dsm_opened_cb: *Jan 2 16:06:52.088: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_process_event: [state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] *Jan 2 16:06:52.088: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/act_setup_req_pend_succ: *Jan 2 16:06:52.088: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_request_call: Mode=0, Peer=20004 *Jan 2 16:06:52.088: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_create_call_active_on_setup_req: Target Carrier ID= *Jan 2 16:06:52.092: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_create_call_active_on_setup_req: Source Carrier ID=htsp_setup_req *Jan 2 16:06:52.092: //23/9E1836A8803B/CCAPI/cc_api_update_call_info: Interface=0x859B9750, Call Id=0x17 *Jan 2 16:06:52.092: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_request_call: Progress Indication=3, Info Trans Capability=144 Calling Number=1234567890, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed Called Number=2345678901, TON=Unknown, NPI=Unknown *Jan 2 16:06:52.096: htsp_process_event: [50/0/4.1, EFXS_ONHOOK, E_HTSP_SETUP_REQ]efxs_onhook_setup *Jan 2 16:06:52.096: htsp_ephone_start_caller_id_tx calling num=1234567890 calling name =BOB SNOW called num=2345678901 orig called num= *Jan 2 16:06:52.096: [50/0/4.1] set signal state = 0x0 timestamp = 0 *Jan 2 16:06:52.104: efxs_onhook_setup: local target is available htsp_alert *Jan 2 16:06:52.108: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_process_event: [state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING] *Jan 2 16:06:52.108: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/act_setup_pend_proceeding: *Jan 2 16:06:52.108: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_timer: Timer Start Time=5494344, Timer Value=10000(ms) *Jan 2 16:06:52.108: //23/9E1836A8803B/CCAPI/cc_api_call_proceeding: Interface=0x859B9750, Progress Indication=NULL(0) *Jan 2 16:06:52.108: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/act_setup_pend_proceeding: Peer=20004, Progress Indication=0, Return Code=2 *Jan 2 16:06:52.112: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_process_event: [state:S_NULL, event:E_TSP_ALERT] *Jan 2 16:06:52.112: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/act_setup_req_pend_defer: Event=6 *Jan 2 16:06:52.140: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_dsm_opened_cb: *Jan 2 16:06:52.144: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_process_event: [state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS] *Jan 2 16:06:52.144: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/act_setup_req_wait_pend_success: Event=72 *Jan 2 16:06:52.144: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_timer_stop: Timer Stop Time=5494348 *Jan 2 16:06:52.144: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/act_setup_pend_alert: Progress Indication=0, Signal Indication=1 *Jan 2 16:06:52.144: //23/9E1836A8803B/CCAPI/cc_api_call_alert: Interface=0x859B9750, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) *Jan 2 16:06:52.148: //23/9E1836A8803B/CCAPI/cc_api_call_alert: Call Entry(Retry Count=0, Responsed=TRUE) *Jan 2 16:06:52.148: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_ring_noan_timer_start: Timer Start Time=5494348, No Answer Timer Value=180000(ms) *Jan 2 16:06:52.148: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/act_setup_pend_alert: Ringback Indication=FALSE, Ring Timeout=180(s) *Jan 2 16:06:52.160: //22/9E1836A8803B/CCAPI/ccCallAlert: Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) *Jan 2 16:06:52.160: //22/9E1836A8803B/CCAPI/ccCallAlert: Call Entry(Responsed=TRUE, AlertSent=TRUE) *Jan 2 16:06:52.164: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_process_event: [state:S_PROCEEDING, event:E_CC_ALERT] *Jan 2 16:06:52.164: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_alert: Progress Indication=0, Signal Indication=1, Setup Progress Indication=3 *Jan 2 16:06:52.164: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_timer_stop: Timer Stop Time=5494350 *Jan 2 16:06:52.164: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_alert: [Ring Back]-Tone Played In Direction [Network]htsp_alert_notify *Jan 2 16:06:52.164: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_alert: Progress Indication=8, Tone=Ring Back *Jan 2 16:06:52.168: htsp_process_event: [1/1/0, FXOLS_PROCEEDING, E_HTSP_ALERT]fxols_offhook_alert *Jan 2 16:06:53.852: htsp_process_event: [1/1/0, FXOLS_PROCEEDING, E_DSP_SIG_0000]fxols_proceed_ring *Jan 2 16:06:53.852: htsp_timer_stop *Jan 2 16:06:54.300: htsp_process_event: [1/1/0, FXOLS_PROCEEDING, E_HTSP_EVENT_TIMER3] *Jan 2 16:06:55.720: htsp_process_event: [1/1/0, FXOLS_PROCEEDING, E_DSP_SIG_0100]fxols_proceed_clear *Jan 2 16:06:55.720: htsp_timer_stop2 *Jan 2 16:06:55.720: htsp_timer - 6000 msec *Jan 2 16:07:01.720: htsp_process_event: [1/1/0, FXOLS_PROCEEDING, E_HTSP_EVENT_TIMER]fxols_disc_confirm *Jan 2 16:07:01.720: htsp_timer_stop *Jan 2 16:07:01.720: htsp_timer_stop2 *Jan 2 16:07:01.720: htsp_timer_stop3 *Jan 2 16:07:01.720: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_process_event: [state:S_ALERTING, event:E_TSP_DISCONNECT_IND] *Jan 2 16:07:01.720: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_generate_disc: Cause Value=16 *Jan 2 16:07:01.720: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_timer_stop: Timer Stop Time=5495306 *Jan 2 16:07:01.724: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_ring_noan_timer_stop: Timer Stop Time=5495306 *Jan 2 16:07:01.724: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_set_release_source: Release Direction=PSTN, Release Source=Calling Party-PSTN *Jan 2 16:07:01.724: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_cc_call_disconnected: *Jan 2 16:07:01.724: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_cc_call_disconnected: Cause Value=16 *Jan 2 16:07:01.724: //22/9E1836A8803B/CCAPI/cc_api_call_disconnected: Cause Value=16, Interface=0x84F4A87C, Call Id=22 *Jan 2 16:07:01.728: //22/9E1836A8803B/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0) *Jan 2 16:07:01.728: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_timer: Timer Start Time=5495306, Timer Value=15000(ms) *Jan 2 16:07:01.728: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_generate_disc: Return Code=0 *Jan 2 16:07:01.728: //22/9E1836A8803B/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Null, Tone Direction=Network, Params=0x0, Call Id=22 *Jan 2 16:07:01.732: //23/9E1836A8803B/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) *Jan 2 16:07:01.732: //23/9E1836A8803B/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) *Jan 2 16:07:01.732: //23/9E1836A8803B/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null *Jan 2 16:07:01.736: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_process_event: [state:S_SETUP_REQ_PROC, event:E_CC_DISCONNECT] *Jan 2 16:07:01.736: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/act_disconnect: Cause Value=16 *Jan 2 16:07:01.736: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_ring_noan_timer_stop: Timer Stop Time=5495307 *Jan 2 16:07:01.736: htsp_timer_stop3 *Jan 2 16:07:01.740: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_dsm_get_levels_done_cb: *Jan 2 16:07:01.740: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_process_event: [state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE] *Jan 2 16:07:01.740: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/act_dsm_dsp_stats_complete: *Jan 2 16:07:01.740: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_timer: Timer Start Time=5495307, Timer Value=60000(ms) *Jan 2 16:07:01.744: htsp_process_event: [50/0/4.1, EFXS_WAIT_OFFHOOK, E_HTSP_RELEASE_REQ]efxs_waitoff_release *Jan 2 16:07:01.744: [50/0/4.1] set signal state = 0x4 timestamp = 0 *Jan 2 16:07:01.752: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_process_event: [state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF] *Jan 2 16:07:01.752: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/act_wrelease_release: *Jan 2 16:07:01.752: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_play_busy_timer_stop: Timer Stop Time=5495308 *Jan 2 16:07:01.752: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_timer_stop: Timer Stop Time=5495308 *Jan 2 16:07:01.752: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_do_call_history: *Jan 2 16:07:01.756: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_do_call_history: Coder Rate=-1 *Jan 2 16:07:01.756: //23/9E1836A8803B/VTSP:(50/0/4):-1:0:7/vtsp_is_valid_dsm_handle: DMGR=0x856B0F7C, VTSP CDB=0x856AEAB0 *Jan 2 16:07:01.792: //23/9E1836A8803B/VTSP:(50/0/4):-1:-1:-1/vtsp_dsm_closed_cb: *Jan 2 16:07:01.796: //23/9E1836A8803B/VTSP:(50/0/4):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] *Jan 2 16:07:01.796: //23/9E1836A8803B/VTSP:(50/0/4):-1:-1:-1/act_terminate: *Jan 2 16:07:01.796: //23/9E1836A8803B/VTSP:(50/0/4):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=5495313 *Jan 2 16:07:01.796: //23/9E1836A8803B/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x859B9750, Tag=0x0, Call Id=23, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) *Jan 2 16:07:01.796: //23/9E1836A8803B/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Jan 2 16:07:01.796: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*Jan 2 16:07:01.800: :cc_free_feature_vsa freeing 8574FC78 *Jan 2 16:07:01.800: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*Jan 2 16:07:01.800: vsacount in free is 1 *Jan 2 16:07:01.800: //-1/9E1836A8803B/VTSP:(50/0/4):-1:-1:-1/vtsp_free_cdb: CDB=0x856AEAB0 *Jan 2 16:07:01.808: //22/9E1836A8803B/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16) *Jan 2 16:07:01.808: //22/9E1836A8803B/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) *Jan 2 16:07:01.812: //22/9E1836A8803B/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null *Jan 2 16:07:01.812: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_process_event: [state:S_WAIT_HOST_DISC, event:E_CC_DISCONNECT] *Jan 2 16:07:01.812: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_host_disc_cleanup: dsm_indicate_error=0, short_cleardown_timeout=1, Mode=0 *Jan 2 16:07:01.812: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_dsm_save_fax_config: *Jan 2 16:07:01.812: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_dsm_save_fax_config: Call Entry=NULL, Call Id=-1 *Jan 2 16:07:01.816: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_host_disc_cleanup: Exit State=16 *Jan 2 16:07:01.816: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_dsm_get_levels_done_cb: *Jan 2 16:07:01.816: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_process_event: [state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE] *Jan 2 16:07:01.816: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_dsm_dsp_stats_complete: *Jan 2 16:07:01.820: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_timer: Timer Start Time=5495315, Timer Value=5000(ms) *Jan 2 16:07:01.820: htsp_process_event: [1/1/0, FXOLS_PROCEEDING, E_HTSP_RELEASE_REQ]fxols_offhook_release *Jan 2 16:07:01.820: htsp_timer_stop *Jan 2 16:07:01.820: htsp_timer_stop2 *Jan 2 16:07:01.820: htsp_timer_stop3 *Jan 2 16:07:01.820: [1/1/0] set signal state = 0x4 timestamp = 0 *Jan 2 16:07:01.824: htsp_timer - 2000 msec *Jan 2 16:07:01.824: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_process_event: [state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF] *Jan 2 16:07:01.824: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/act_wrelease_release: *Jan 2 16:07:01.824: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_play_busy_timer_stop: Timer Stop Time=5495316 *Jan 2 16:07:01.824: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_timer_stop: Timer Stop Time=5495316 *Jan 2 16:07:01.828: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_do_call_history: *Jan 2 16:07:01.828: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_do_call_history: Coder Rate=-1 *Jan 2 16:07:01.828: //22/9E1836A8803B/VTSP:(1/1/0):-1:3:1/vtsp_is_valid_dsm_handle: DMGR=0x85A3370C, VTSP CDB=0x856AE7F0 *Jan 2 16:07:01.832: //22/9E1836A8803B/VTSP:(1/1/0):-1:-1:-1/vtsp_dsm_closed_cb: *Jan 2 16:07:01.836: //22/9E1836A8803B/VTSP:(1/1/0):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] *Jan 2 16:07:01.836: //22/9E1836A8803B/VTSP:(1/1/0):-1:-1:-1/act_terminate: *Jan 2 16:07:01.836: //22/9E1836A8803B/VTSP:(1/1/0):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=5495317 *Jan 2 16:07:01.836: //22/9E1836A8803B/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x84F4A87C, Tag=0x0, Call Id=22, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) *Jan 2 16:07:01.836: //22/9E1836A8803B/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Jan 2 16:07:01.840: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*Jan 2 16:07:01.840: :cc_free_feature_vsa freeing 8574FD50 *Jan 2 16:07:01.840: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*Jan 2 16:07:01.840: vsacount in free is 0 *Jan 2 16:07:01.840: //-1/9E1836A8803B/VTSP:(1/1/0):-1:-1:-1/vtsp_free_cdb: CDB=0x856AE7F0 *Jan 2 16:07:03.824: htsp_process_event: [1/1/0, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout *Jan 2 16:07:03.824: htsp_process_event: [1/1/0, FXOLS_ONHOOK, E_DSP_SIG_0100] *Jan 2 16:10:01.844: //-1/xxxxxxxxxxxx/CCAPI/ccAppShutdownMode: ccAppShutdownMode: remove it from the queue |