*Apr 18 13:21:36.251: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=30080 systime=-1606409220 *Apr 18 13:21:36.251: htsp_process_event: [0/0/6, FXSLS_ONHOOK, E_DSP_SIG_1100]fxsls_onhook_offhook htsp_setup_ind *Apr 18 13:21:36.251: [0/0/6] get_local_station_id calling num=+441438551097 calling name= calling time=04/18 13:21 orig called= *Apr 18 13:21:36.251: //-1/xxxxxxxxxxxx/VTSP:(0/0/6):-1:-1:-1/vtsp_allocate_cdb: CDB=0x226F1F88 *Apr 18 13:21:36.251: //-1/xxxxxxxxxxxx/VTSP:(0/0/6):-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 *Apr 18 13:21:36.251: //-1/xxxxxxxxxxxx/VTSP:(0/0/6):-1:-1:-1/vtsp_do_call_setup_ind: Calling Number=+441438551097, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed CLIR=FALSE, CLID Transparent=FALSE, Null Originating Calling Number=FALSE, Calling Translated=FALSE *Apr 18 13:21:36.251: //-1/411D4008A818/VTSP:(0/0/6):-1:-1:-1/vtsp_do_normal_call_setup_ind: *Apr 18 13:21:36.251: //-1/411D4008A818/VTSP:(0/0/6):-1:-1:-1/vtsp_timer: Timer Start Time=-1606409220, Timer Value=180000(ms) *Apr 18 13:21:36.251: //-1/411D4008A818/VTSP:(0/0/6):-1:-1:-1/vtsp_do_normal_call_setup_ind: IC State Machine (ICSM) Added *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = -1, confID = -1, streamType = TDM, streamDir = INPUT, ownerContext = 0x0, *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: codec = 0x0, fax_modem_type=0, XmitFn = 0x0, packetStreamSSRC =0x0 , multicastStreamDtmfType = 0 *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 5562 *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateDmgrSessId: sId = 5562, dmgr_sess_id = 404 *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = -1, confID = -1, streamType = TDM, streamDir = OUTPUT, ownerContext = 0x0, *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: codec = 0x0, fax_modem_type=0, XmitFn = 0x0, packetStreamSSRC =0x0 , multicastStreamDtmfType = 0 *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 5561 *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateDmgrSessId: sId = 5561, dmgr_sess_id = 404 *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpReserveGwResource: sIdLegInput1 = 5562, sIdLegOutput1 = 5561, requesterCallID = -1, respFunc = 0x3487924C *Apr 18 13:21:36.251: //-1/411D4008A818/VTSP:(0/0/6):-1:-1:-1/vtsp_insert_cdb: *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_new_or_existing_gw_session: created a new session = 0x3A2A17C0 *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_enlist_stream: Stream 5562 is enlisted, total = 1 *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_enlist_stream: Stream 5561 is enlisted, total = 2 *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_exec: [state:S_DSMP_INITIAL, event:E_DSMP_CC_RESERVE_RESOURCE_REQ] *Apr 18 13:21:36.251: [0x222AAC84]CNFSM: cur_container:rsc_main_container, cur_state:S_DSMP_INITIAL, event:E_DSMP_CC_RESERVE_RESOURCE_REQ *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_gw_act_alloc_rsc: *Apr 18 13:21:36.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_decide_pgm_based_stm: *Apr 18 13:21:36.251: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:21:36.251: [0x222AAC84]CNFSM: new_container:rsc_allocating_container *Apr 18 13:21:36.251: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_ALLOCATING *Apr 18 13:21:36.251: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_rsc_alloc_succ: *Apr 18 13:21:36.251: //-1/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_opened_cb: *Apr 18 13:21:36.255: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_ALLOCATED *Apr 18 13:21:36.255: //-1/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] *Apr 18 13:21:36.255: //-1/411D4008A818/VTSP:(0/0/6):-1:1:1/act_setup_ind_pend_success: *Apr 18 13:21:36.255: //-1/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_realloc_cdb: CDB=0x226F1F88 *Apr 18 13:21:36.255: //-1/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_indicate_call: *Apr 18 13:21:36.255: //-1/411D4008A818/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=+441438551097 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 *Apr 18 13:21:36.255: //-1/411D4008A818/CCAPI/cc_api_call_setup_ind_common: Interface=0x39B07D64, Call Info( Calling Number=+441438551097,(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=99926, 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 *Apr 18 13:21:36.255: //-1/411D4008A818/CCAPI/ccCheckClipClir: In: Calling Number=+441438551097(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Apr 18 13:21:36.255: //-1/411D4008A818/CCAPI/ccCheckClipClir: Out: Calling Number=+441438551097(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Apr 18 13:21:36.255: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 18 13:21:36.255: :cc_get_feature_vsa malloc success *Apr 18 13:21:36.255: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 18 13:21:36.255: cc_get_feature_vsa count is 1 *Apr 18 13:21:36.255: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 18 13:21:36.255: :FEATURE_VSA attributes are: feature_name:0,feature_time:562552312,feature_id:18360 *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/cc_api_call_setup_ind_common: Set Up Event Sent; Call Info(Calling Number=+441438551097(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=(TON=Unknown, NPI=Unknown)) *Apr 18 13:21:36.255: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateStreamCallID: sId = 5562, new_callid = 880958 *Apr 18 13:21:36.255: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateStreamCallID: sId = 5561, new_callid = 880958 *Apr 18 13:21:36.255: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_insert_cdb: *Apr 18 13:21:36.255: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer: Timer Start Time=-1606409220, Timer Value=180000(ms) *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/cc_process_call_setup_ind: Event=0x40C8AE98 *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/ccCallSetContext: Context=0x37025400 *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 880958 with tag 99926 to app "_ManagedAppProcess_Default" *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/ccCallSetupAck: Call Id=880958 *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/cc_api_set_transfer_info: Transfer Number=, Transfer Reason=0x0 *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=TRUE, Tone=Dial Tone, Tone Direction=Network, Params=0x0, Call Id=880958 *Apr 18 13:21:36.255: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpPlayReq: sIdLegInput = 5562, sIdLegOutput = 5561, callID = 880958, play_type = 6 *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/ccSetDigitTimeouts: Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms) *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/ccSetDigitTimeouts: Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms)) *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/ccCallReportDigits: (callID=0xD713E, digit_event=0x1, enable=TRUE, consume=FALSE) *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=880958 *Apr 18 13:21:36.255: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_report_digit_control: *Apr 18 13:21:36.255: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpListenReq: sIdLegInput = 5562, sIdLegOutput = 5561, callID = 880958, det_type = 0x200000000000, enable = 1 det_info = 0x0 *Apr 18 13:21:36.255: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpListenReq: sIdLegInput = 5562, sIdLegOutput = 5561, callID = 880958, det_type = 0x200000000000, enable = 1 det_info = 0x0 *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x39B07D64, callID=0xD713E, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:36.255: //880958/411D4008A818/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Apr 18 13:21:36.255: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_report_digit_control: Digit Reporting=TRUE *Apr 18 13:21:36.255: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_SETUP_INDICATED, event:E_CC_SETUP_ACK] *Apr 18 13:21:36.255: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/act_setup_ind_ack: Fax Relay=TRUE, Dial Tone=TRUE, Digit Collect=TRUE, Overlap=FALSE, DID=FALSE *Apr 18 13:21:36.255: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606409220 *Apr 18 13:21:36.255: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpListenReq: sIdLegInput = 5562, sIdLegOutput = 5561, callID = 880958, det_type = 0x800, enable = 1 det_info = 0x21ED7DA8 *Apr 18 13:21:36.255: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: sIdLegInput = 0, sIdLegOutput = 0, callID = 880958 *Apr 18 13:21:36.255: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: SOFTWARE_ERROR_INFORMATIONAL; Invalid Stream ID sIdLegInput=0, sIdLegOutput=0 *Apr 18 13:21:36.259: htsp_process_event: [0/0/6, FXSLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]fxsls_check_auto_call *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_RSC_ALLOCATED, event:E_DSMP_CC_PLAY_REQ] *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: cur_container:rsc_allocating_container, cur_state:S_DSMP_RSC_ALLOCATED, event:E_DSMP_CC_PLAY_REQ *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_dsp_not_allocated: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_dsp_not_allocated: DSP Allocated=TRUE *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_req_not_in_proc_dsp_ready: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_rsc_init: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: history stored state: S_DSMP_RSC_ALLOCATED, container: rsc_allocating_container *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_INITING *Apr 18 13:21:36.259: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_decide_pgm_based_stm: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_rsc_init_succ: pgm_base_stm_id = 5561, service_id = 5, codec =1 *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP pending *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_OPENING *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_rsc_pend: *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_RSC_OPENING, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: cur_container:rsc_main_container, cur_state:S_DSMP_RSC_OPENING, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_defer: *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_RSC_OPENING, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: cur_container:rsc_main_container, cur_state:S_DSMP_RSC_OPENING, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_defer: *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_RSC_OPENING, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: cur_container:rsc_main_container, cur_state:S_DSMP_RSC_OPENING, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_defer: *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_RSC_OPENING, event:E_DSMP_DSPRM_PEND_SUCCESS] *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: cur_container:rsc_main_container, cur_state:S_DSMP_RSC_OPENING, event:E_DSMP_DSPRM_PEND_SUCCESS *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_rsc_open_succ: *Apr 18 13:21:36.259: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_static_params: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_static_params: service_type=1 base stream=5561 tdm_caps=0x2251B6FC pkt_caps=0x0 *Apr 18 13:21:36.259: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_static_params: voice detection disabled *Apr 18 13:21:36.259: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_static_params: silence detection disabled *Apr 18 13:21:36.259: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_static_params: tty detection disabled *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_STATIC_CONF *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_start_service: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_STARTING *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_act_rsc_start_succ: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_dynamic_params: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_dynamic_params: servic_type=1 base stream=5561 *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_tty_params: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_tty_params: base stream=5561 pkt_caps=0x0 *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_dynamic_params: tty params conf failed *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_check_and_start_cpa: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_check_and_start_cpa: base stream=5561 *Apr 18 13:21:36.259: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: *Apr 18 13:21:36.259: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: *Apr 18 13:21:36.259: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_detector_params: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_DYNAMIC_CONF *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_H32X_CHECK *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_h32x: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_h320: *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_MCAST_CHECK *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_mcast: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_mcast:exit@2088 *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_not_mcast: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_mcast: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_mcast:exit@2088 *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_not_mcast:exit@2101 *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_DTMF_FSK_MODE_CHECK *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_fsk_or_dtmf: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_fsk_or_dtmf:exit@2179 *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_FSK_OR_DTMF *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_digit_signalling_fsk_tx: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_digit_signalling_fsk_rx: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_digit_signalling_dtmf: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_digit_signalling_fsk_rx: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_digit_signalling_fsk_tx: *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_digit_signalling_dtmf:exit@2281 *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_dtmf_start: *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: new_container:dtmf_sig_container *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_DTSIG_RUNNING *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_play_tone: *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: next_state:S_DSMP_DTSIG_PLAYING_TONE *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_PLAYING_TONE, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_PLAYING_TONE, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_detect_sup_tone: *Apr 18 13:21:36.259: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:21:36.259: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_defer: *Apr 18 13:21:36.263: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:36.263: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:36.263: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:36.263: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_PLAYING_TONE, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:36.263: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_PLAYING_TONE, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:36.263: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:36.263: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_detect_sup_tone: *Apr 18 13:21:36.263: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:21:36.263: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_defer: *Apr 18 13:21:36.263: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:36.263: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:36.263: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:36.263: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_PLAYING_TONE, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:36.263: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_PLAYING_TONE, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:36.263: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:36.263: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_detect_sup_tone: *Apr 18 13:21:36.263: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:21:36.263: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_defer: *Apr 18 13:21:36.263: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:37.983: %SYS-5-CONFIG_I: Configured from console by obsadmin on vty0 (10.252.128.46) *Apr 18 13:21:38.643: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_PLAYING_TONE, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN] *Apr 18 13:21:38.643: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_PLAYING_TONE, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN *Apr 18 13:21:38.643: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:38.643: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_BEGIN, callback = 0x34871BE4 *Apr 18 13:21:38.643: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: *Apr 18 13:21:38.643: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Begin Digit=8 *Apr 18 13:21:38.643: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:38.643: //880958/411D4008A818/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=8, DigitBeginFlags=0x1, Rtp Timestamp=0xF03F4749, Rtp Expiration=0x0 *Apr 18 13:21:38.643: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_PLAYING_TONE, event:E_DSMP_DSP_DTMF_DIGIT_END] *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_PLAYING_TONE, event:E_DSMP_DSP_DTMF_DIGIT_END *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_digit_end_detected_stop_tone: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_digit_end_detected: Digit=8 *Apr 18 13:21:38.735: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:38.735: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_END, callback = 0x34871BE4 *Apr 18 13:21:38.735: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: *Apr 18 13:21:38.735: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606408972 *Apr 18 13:21:38.735: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpStopPlay: sIdLegOutput = 5561, callID = 880958, play_type = 6 *Apr 18 13:21:38.735: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: End Digit=8 *Apr 18 13:21:38.735: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:38.735: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=8, Duration=125, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Apr 18 13:21:38.735: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Apr 18 13:21:38.735: htsp_digit_ready(0/0/6): digit = 8 *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: next_state:S_DSMP_DTSIG_RUNNING *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_detect_sup_tone: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_save_listen_caps: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_save_stream_detectors: *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: history stored state: S_DSMP_DTSIG_RUNNING, container: dtmf_sig_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: updated current container: rsc_running_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: next_state:S_DSMP_MODIFY_CAPS_SAVED *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_static_params_changed: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_dynamic_params_changed: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_no_param_changed: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_no_param_changed:exit@2029 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_modify_no_param_changed: *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: restoring history state: S_DSMP_DTSIG_RUNNING *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: restoring history container: dtmf_sig_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: new_container:dtmf_sig_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: next_state:S_DSMP_DTSIG_RUNNING *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_detect_sup_tone: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_save_listen_caps: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_save_stream_detectors: *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: history stored state: S_DSMP_DTSIG_RUNNING, container: dtmf_sig_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: updated current container: rsc_running_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: next_state:S_DSMP_MODIFY_CAPS_SAVED *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_static_params_changed: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_dynamic_params_changed: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_no_param_changed: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_no_param_changed:exit@2029 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_modify_no_param_changed: *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: restoring history state: S_DSMP_DTSIG_RUNNING *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: restoring history container: dtmf_sig_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: new_container:dtmf_sig_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: next_state:S_DSMP_DTSIG_RUNNING *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_detect_sup_tone: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_save_listen_caps: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_save_stream_detectors: *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: history stored state: S_DSMP_DTSIG_RUNNING, container: dtmf_sig_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: updated current container: rsc_running_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: next_state:S_DSMP_MODIFY_CAPS_SAVED *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_static_params_changed: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_dynamic_params_changed: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_dynamic_params_changed: *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_detector_params: *Apr 18 13:21:38.735: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_detector_params: digit: 0 *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: restoring history state: S_DSMP_DTSIG_RUNNING *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: restoring history container: dtmf_sig_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: new_container:dtmf_sig_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: next_state:S_DSMP_DTSIG_RUNNING *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ] *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:21:38.735: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:21:38.735: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:38.843: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN] *Apr 18 13:21:38.843: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN *Apr 18 13:21:38.843: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:38.843: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_BEGIN, callback = 0x34871BE4 *Apr 18 13:21:38.843: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: *Apr 18 13:21:38.843: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Begin Digit=7 *Apr 18 13:21:38.843: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:38.843: //880958/411D4008A818/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=7, DigitBeginFlags=0x1, Rtp Timestamp=0xF03F4D8C, Rtp Expiration=0x0 *Apr 18 13:21:38.843: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:38.943: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END] *Apr 18 13:21:38.943: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END *Apr 18 13:21:38.943: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_digit_end_detected: Digit=7 *Apr 18 13:21:38.943: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:38.943: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_END, callback = 0x34871BE4 *Apr 18 13:21:38.943: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: *Apr 18 13:21:38.943: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606408951 *Apr 18 13:21:38.943: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: End Digit=7 *Apr 18 13:21:38.943: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:38.943: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=7, Duration=132, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Apr 18 13:21:38.943: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Apr 18 13:21:38.943: htsp_digit_ready(0/0/6): digit = 7 *Apr 18 13:21:38.943: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:39.043: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN] *Apr 18 13:21:39.043: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN *Apr 18 13:21:39.043: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:39.043: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_BEGIN, callback = 0x34871BE4 *Apr 18 13:21:39.043: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: *Apr 18 13:21:39.043: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Begin Digit=8 *Apr 18 13:21:39.043: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:39.043: //880958/411D4008A818/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=8, DigitBeginFlags=0x1, Rtp Timestamp=0xF03F53CC, Rtp Expiration=0x0 *Apr 18 13:21:39.043: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:39.143: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END] *Apr 18 13:21:39.143: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END *Apr 18 13:21:39.143: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_digit_end_detected: Digit=8 *Apr 18 13:21:39.143: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:39.143: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_END, callback = 0x34871BE4 *Apr 18 13:21:39.143: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: *Apr 18 13:21:39.143: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606408931 *Apr 18 13:21:39.143: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: End Digit=8 *Apr 18 13:21:39.143: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:39.143: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=8, Duration=132, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Apr 18 13:21:39.143: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Apr 18 13:21:39.143: htsp_digit_ready(0/0/6): digit = 8 *Apr 18 13:21:39.143: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:39.251: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN] *Apr 18 13:21:39.251: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN *Apr 18 13:21:39.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:39.251: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_BEGIN, callback = 0x34871BE4 *Apr 18 13:21:39.251: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: *Apr 18 13:21:39.251: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Begin Digit=1 *Apr 18 13:21:39.251: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:39.251: //880958/411D4008A818/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=1, DigitBeginFlags=0x1, Rtp Timestamp=0xF03F5A57, Rtp Expiration=0x0 *Apr 18 13:21:39.251: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:39.343: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END] *Apr 18 13:21:39.343: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END *Apr 18 13:21:39.343: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_digit_end_detected: Digit=1 *Apr 18 13:21:39.343: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:39.343: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_END, callback = 0x34871BE4 *Apr 18 13:21:39.343: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: *Apr 18 13:21:39.343: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606408911 *Apr 18 13:21:39.343: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: End Digit=1 *Apr 18 13:21:39.343: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:39.343: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=1, Duration=127, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Apr 18 13:21:39.343: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Apr 18 13:21:39.343: htsp_digit_ready(0/0/6): digit = 1 *Apr 18 13:21:39.343: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:39.451: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN] *Apr 18 13:21:39.451: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN *Apr 18 13:21:39.451: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:39.451: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_BEGIN, callback = 0x34871BE4 *Apr 18 13:21:39.451: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: *Apr 18 13:21:39.451: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Begin Digit=3 *Apr 18 13:21:39.451: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:39.451: //880958/411D4008A818/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=3, DigitBeginFlags=0x1, Rtp Timestamp=0xF03F6097, Rtp Expiration=0x0 *Apr 18 13:21:39.451: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:39.543: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END] *Apr 18 13:21:39.543: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END *Apr 18 13:21:39.543: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_digit_end_detected: Digit=3 *Apr 18 13:21:39.543: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:39.543: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_END, callback = 0x34871BE4 *Apr 18 13:21:39.543: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: *Apr 18 13:21:39.543: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606408891 *Apr 18 13:21:39.543: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: End Digit=3 *Apr 18 13:21:39.543: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:39.543: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=3, Duration=127, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Apr 18 13:21:39.543: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Apr 18 13:21:39.543: htsp_digit_ready(0/0/6): digit = 3 *Apr 18 13:21:39.543: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:39.651: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN] *Apr 18 13:21:39.651: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN *Apr 18 13:21:39.651: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:39.651: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_BEGIN, callback = 0x34871BE4 *Apr 18 13:21:39.651: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: *Apr 18 13:21:39.651: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Begin Digit=3 *Apr 18 13:21:39.651: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:39.651: //880958/411D4008A818/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=3, DigitBeginFlags=0x1, Rtp Timestamp=0xF03F66D9, Rtp Expiration=0x0 *Apr 18 13:21:39.651: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:39.743: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END] *Apr 18 13:21:39.743: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END *Apr 18 13:21:39.743: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_digit_end_detected: Digit=3 *Apr 18 13:21:39.743: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:39.743: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_END, callback = 0x34871BE4 *Apr 18 13:21:39.743: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: *Apr 18 13:21:39.743: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606408871 *Apr 18 13:21:39.743: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: End Digit=3 *Apr 18 13:21:39.743: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:39.743: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=3, Duration=125, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Apr 18 13:21:39.743: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Apr 18 13:21:39.743: htsp_digit_ready(0/0/6): digit = 3 *Apr 18 13:21:39.743: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:39.851: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN] *Apr 18 13:21:39.851: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN *Apr 18 13:21:39.851: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:39.851: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_BEGIN, callback = 0x34871BE4 *Apr 18 13:21:39.851: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: *Apr 18 13:21:39.851: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Begin Digit=0 *Apr 18 13:21:39.851: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:39.851: //880958/411D4008A818/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=0, DigitBeginFlags=0x1, Rtp Timestamp=0xF03F6D1C, Rtp Expiration=0x0 *Apr 18 13:21:39.851: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:39.951: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END] *Apr 18 13:21:39.951: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END *Apr 18 13:21:39.951: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_digit_end_detected: Digit=0 *Apr 18 13:21:39.951: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:39.951: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_END, callback = 0x34871BE4 *Apr 18 13:21:39.951: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: *Apr 18 13:21:39.951: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606408850 *Apr 18 13:21:39.951: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: End Digit=0 *Apr 18 13:21:39.951: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:39.951: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=0, Duration=132, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Apr 18 13:21:39.951: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Apr 18 13:21:39.951: htsp_digit_ready(0/0/6): digit = 0 *Apr 18 13:21:39.951: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:40.051: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN] *Apr 18 13:21:40.051: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_BEGIN *Apr 18 13:21:40.051: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:40.051: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_BEGIN, callback = 0x34871BE4 *Apr 18 13:21:40.051: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: *Apr 18 13:21:40.051: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Begin Digit=0 *Apr 18 13:21:40.051: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_begin_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:40.051: //880958/411D4008A818/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=0, DigitBeginFlags=0x1, Rtp Timestamp=0xF03F735C, Rtp Expiration=0x0 *Apr 18 13:21:40.051: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:40.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END] *Apr 18 13:21:40.151: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_DSP_DTMF_DIGIT_END *Apr 18 13:21:40.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_digit_end_detected: Digit=0 *Apr 18 13:21:40.151: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:40.151: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_DTMF_DIGIT_END, callback = 0x34871BE4 *Apr 18 13:21:40.151: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: *Apr 18 13:21:40.151: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606408830 *Apr 18 13:21:40.151: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: End Digit=0 *Apr 18 13:21:40.151: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_digit_end_cb: Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=FALSE *Apr 18 13:21:40.151: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=880958, Digit=0, Duration=132, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF *Apr 18 13:21:40.151: //880958/411D4008A818/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) *Apr 18 13:21:40.151: htsp_digit_ready(0/0/6): digit = 0 *Apr 18 13:21:40.151: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/ccCallReportDigits: (callID=0xD713E, digit_event=0x0, enable=FALSE, consume=FALSE) *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=880958 *Apr 18 13:21:50.151: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_report_digit_control: *Apr 18 13:21:50.151: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpListenReq: sIdLegInput = 5562, sIdLegOutput = 5561, callID = 880958, det_type = 0x200000000000, enable = 0 det_info = 0x0 *Apr 18 13:21:50.151: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpListenReq: sIdLegInput = 5562, sIdLegOutput = 5561, callID = 880958, det_type = 0x200000000000, enable = 0 det_info = 0x0 *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x39B07D64, callID=0xD713E, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE) *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Apr 18 13:21:50.151: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_report_digit_control: Digit Reporting=FALSE *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_detect_sup_tone: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_save_listen_caps: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_save_stream_detectors: *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: history stored state: S_DSMP_DTSIG_RUNNING, container: dtmf_sig_container *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: updated current container: rsc_running_container *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: next_state:S_DSMP_MODIFY_CAPS_SAVED *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_static_params_changed: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_dynamic_params_changed: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_no_param_changed: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_no_param_changed:exit@2029 *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_modify_no_param_changed: *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: restoring history state: S_DSMP_DTSIG_RUNNING *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: restoring history container: dtmf_sig_container *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: new_container:dtmf_sig_container *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: next_state:S_DSMP_DTSIG_RUNNING *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_LISTEN_REQ] *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_LISTEN_REQ *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_detect_sup_tone: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_save_listen_caps: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_save_stream_detectors: *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: history stored state: S_DSMP_DTSIG_RUNNING, container: dtmf_sig_container *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: updated current container: rsc_running_container *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: next_state:S_DSMP_MODIFY_CAPS_SAVED *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_static_params_changed: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_dynamic_params_changed: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru:exit@1428 *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_no_param_changed: *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_no_param_changed:exit@2029 *Apr 18 13:21:50.151: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_modify_no_param_changed: *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: restoring history state: S_DSMP_DTSIG_RUNNING *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: restoring history container: dtmf_sig_container *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: new_container:dtmf_sig_container *Apr 18 13:21:50.151: [0x222AAC84]CNFSM: next_state:S_DSMP_DTSIG_RUNNING *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/ccCallProceeding: Progress Indication=NULL(0) *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=100, Params=0x3A250BD4, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/ccCheckClipClir: In: Calling Number=+441438551097(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/ccCheckClipClir: Out: Calling Number=+441438551097(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/ccCallSetupRequest: Destination Pattern=.T, Called Number=87813300, Digit Strip=FALSE *Apr 18 13:21:50.151: //880958/411D4008A818/CCAPI/ccCallSetupRequest: Calling Number=+441438551097(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=87813300(TON=Unknown, NPI=Unknown), Redirect Number=, Display Info= Account Number=, Final Destination Flag=FALSE, Guid=411D4008-4242-11E8-A818-E948B0BBB202, Outgoing Dial-peer=100 *Apr 18 13:21:50.155: //880958/411D4008A818/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=+441438551097 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=87813300 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 *Apr 18 13:21:50.155: //880958/411D4008A818/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x40B81140, Interface Type=3, Destination=, Mode=0x0, Call Params(Calling Number=+441438551097,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=87813300(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=100, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) *Apr 18 13:21:50.155: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 18 13:21:50.155: :cc_get_feature_vsa malloc success *Apr 18 13:21:50.155: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 18 13:21:50.155: cc_get_feature_vsa count is 2 *Apr 18 13:21:50.155: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 18 13:21:50.155: :FEATURE_VSA attributes are: feature_name:0,feature_time:562550072,feature_id:18361 *Apr 18 13:21:50.155: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_spi_event_handler: Event Category=1, Event=189 *Apr 18 13:21:50.155: //880959/411D4008A818/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1 *Apr 18 13:21:50.155: //880959/411D4008A818/CCAPI/ccCallSetContext: Context=0x3A250B84 *Apr 18 13:21:50.155: //880958/411D4008A818/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=100 *Apr 18 13:21:50.155: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_DIGIT_COLLECT, event:E_CC_PROCEEDING] *Apr 18 13:21:50.155: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/act_dcollect_proc: *Apr 18 13:21:50.155: htsp_process_event: [0/0/6, FXSLS_OFFHOOK, E_HTSP_PROCEEDING] *Apr 18 13:21:50.155: //880959/411D4008A818/CCAPI/cc_api_call_proceeding: Interface=0x40B81140, Progress Indication=NULL(0) *Apr 18 13:21:50.159: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Sent: INVITE sip:87813300@10.251.128.131:5060 SIP/2.0 Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK8878F2327 Remote-Party-ID: ;party=calling;screen=no;privacy=off From: ;tag=42819E2C-A20 To: Date: Wed, 18 Apr 2018 13:21:50 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 Supported: 100rel,timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Cisco-Guid: 1092435976-1111626216-2820204872-2965090818 User-Agent: Cisco-SIPGateway/IOS-15.4.3.M2 Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Max-Forwards: 70 Timestamp: 1524057710 Contact: Call-Info: ;method="NOTIFY;Event=telephone-event;Duration=2000" Expires: 86 Allow-Events: telephone-event Content-Type: application/sdp Content-Disposition: session;handling=required Content-Length: 324 v=0 o=CiscoSystemsSIP-GW-UserAgent 4691 9271 IN IP4 172.23.199.166 s=SIP Call c=IN IP4 172.23.199.166 t=0 0 m=audio 20192 RTP/AVP 8 0 18 101 c=IN IP4 172.23.199.166 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 *Apr 18 13:21:50.159: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK8878F2327 From: ;tag=42819E2C-A20 To: Date: Wed, 18 Apr 2018 13:28:49 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 CSeq: 101 INVITE Allow-Events: presence Content-Length: 0 *Apr 18 13:21:50.347: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK8878F2327 From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:28:49 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 CSeq: 101 INVITE Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence Server: Cisco-CUCM10.5 Supported: X-cisco-srtp-fallback Supported: Geolocation P-Asserted-Identity: Remote-Party-ID: ;party=called;screen=yes;privacy=off Contact: Content-Type: application/sdp Content-Length: 253 v=0 o=CiscoSystemsCCM-SIP 604811112 1 IN IP4 10.251.128.131 s=SIP Call c=IN IP4 10.252.255.236 b=TIAS:64000 b=CT:64 b=AS:64 t=0 0 m=audio 19212 RTP/AVP 0 101 a=ptime:20 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 *Apr 18 13:21:50.347: //880959/411D4008A818/CCAPI/cc_api_call_alert: Interface=0x40B81140, Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1) *Apr 18 13:21:50.347: //880959/411D4008A818/CCAPI/cc_api_call_alert: Call Entry(Retry Count=0, Responsed=TRUE) *Apr 18 13:21:50.351: //880958/411D4008A818/CCAPI/ccCallAlert: Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1) *Apr 18 13:21:50.351: //880958/411D4008A818/CCAPI/ccCallAlert: Call Entry(Responsed=TRUE, Alert Sent=TRUE) *Apr 18 13:21:50.351: //880958/411D4008A818/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFF, callID1=0xD713E, gcid=411D4008-424211E8-A818E948-B0BBB202, tag=0x0) *Apr 18 13:21:50.351: //880959/411D4008A818/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFF, callID2=0xD713F, gcid=411D4008-424211E8-A818E948-B0BBB202, tag=0x0) *Apr 18 13:21:50.351: //880958/411D4008A818/CCAPI/ccConferenceCreate: Conference Id=0xFFFFFFFF, Call Id1=880958, Call Id2=880959, Tag=0x0 *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_bridge_check_cb: *Apr 18 13:21:50.351: htsp_call_bridged invoked *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_bridge_check_cb:exit@1505 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = 880959, confID = 295, streamType = PACKET, streamDir = INPUT, ownerContext = 0x0, *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: codec = 0x1, fax_modem_type=0, XmitFn = 0x34DBB230, packetStreamSSRC =0x0 , multicastStreamDtmfType = -1 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: set xmit_fn:0x34DBB230 for callID:880959 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 5560 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateDmgrSessId: sId = 5560, dmgr_sess_id = 404 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = 880959, confID = 295, streamType = PACKET, streamDir = OUTPUT, ownerContext = 0x0, *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: codec = 0x1, fax_modem_type=0, XmitFn = 0x34DBB230, packetStreamSSRC =0x0 , multicastStreamDtmfType = -1 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: set xmit_fn:0x34DBB230 callID:880959 encap:1 media:0 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 5559 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateDmgrSessId: sId = 5559, dmgr_sess_id = 404 *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_bridge_status_cb: *Apr 18 13:21:50.351: //880958/411D4008A818/CCAPI/cc_api_bridge_done: Conference Id=0x127, Source Interface=0x39B07D64, Source Call Id=880958, Destination Call Id=880959, Disposition=0x0, Tag=0xFFFFFFFF *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_save_fax_config: *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=T38_FAX_RELAY, Fallback Fax Protocol=FAXPASSTHROUGH_FAX Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=Dialpeer, Peer=100 *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_save_fax_config: Fax Version:=0, LS Red=4, HS Red=1 *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_save_fax_config: Passthrough Codec=g711ulaw *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_set_fax_feat_param: *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_save_tty_config: *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_save_tty_config: Save TTY configuration *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_set_tty_feat_param: *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_set_tty_feat_param: Set TTY feat parameters *Apr 18 13:21:50.351: //880959/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: *Apr 18 13:21:50.351: cc_api_get_xcode_stream : 4982 *Apr 18 13:21:50.351: //880959/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: *Apr 18 13:21:50.351: cc_api_get_xcode_stream : 4982 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 5560, callID = 880958, mode = 3 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 5560, callID = 880958, mode = 3 *Apr 18 13:21:50.351: //880959/411D4008A818/CCAPI/cc_api_bridge_done: Conference Id=0x127, Source Interface=0x40B81140, Source Call Id=880959, Destination Call Id=880958, Disposition=0x0, Tag=0x0 *Apr 18 13:21:50.351: //880958/411D4008A818/CCAPI/cc_generic_bridge_done: Conference Id=0x127, Source Interface=0x40B81140, Source Call Id=880959, Destination Call Id=880958, Disposition=0x0, Tag=0x0 *Apr 18 13:21:50.351: //880958/411D4008A818/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x127, Destination Call Id=880959) *Apr 18 13:21:50.351: //880959/411D4008A818/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x127, Destination Call Id=880958) *Apr 18 13:21:50.351: //880958/411D4008A818/CCAPI/ccConferenceCreate: *Apr 18 13:21:50.351: confID:0x127; callEntry1 callID1:0xD713E, type:6; callEntry2 callID2:0xD713F, type:3 *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_PROCEEDING, event:E_CC_ALERT] *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/act_alert: Progress Indication=8, Signal Indication=1, Setup Progress Indication=3 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: sIdLegInput = 5560, sIdLegOutput = 5559, callID = 880958 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: Copying nominator(0x21ED7D98) to req_data.. *Apr 18 13:21:50.351: Modify Nominator = *Apr 18 13:21:50.351: PAK_SUPRESS *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606407810htsp_alert_notify *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_open_packet_path: Progress Indication=8 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: sIdLegInput = 5560, sIdLegOutput = 5559, callID = 880958 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: Copying nominator(0x21ED7D50) to req_data.. *Apr 18 13:21:50.351: Modify Nominator = *Apr 18 13:21:50.351: PAK_SUPRESS *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_open_packet_path: Setting pak_suppress to DSP_PAK_SUPPRESS_DISABLE *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/act_alert: Progress Indication=8 *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_ALERTING, event:E_CC_DO_CAPS_IND] *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_IND *Apr 18 13:21:50.351: //880958/411D4008A818/CCAPI/cc_api_caps_ind: Destination Interface=0x40B81140, Destination Call Id=880959, Source Call Id=880958, Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=20, Signal Type=3) *Apr 18 13:21:50.351: //880958/411D4008A818/CCAPI/cc_api_caps_ind: Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms), Playout Max=1000(ms), Fax Nom=300(ms)) *Apr 18 13:21:50.351: //880959/411D4008A818/CCAPI/cc_api_caps_ind: Destination Interface=0x39B07D64, Destination Call Id=880958, Source Call Id=880959, Caps(Codec=0x1, Fax Rate=0x80, Fax Version:=0, Vad=0x1, Modem=0x0, Codec Bytes=160, Signal Type=2) *Apr 18 13:21:50.351: //880959/411D4008A818/CCAPI/cc_api_caps_ind: Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms), Playout Max=1000(ms), Fax Nom=300(ms)) *Apr 18 13:21:50.351: //880959/411D4008A818/CCAPI/cc_api_caps_ack: Destination Interface=0x39B07D64, Destination Call Id=880958, Source Call Id=880959, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_14400(0x80), Fax Version:=0, Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=8773) *Apr 18 13:21:50.351: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_ALERTING, event:E_CC_CAPS_IND] *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDisconnectGwBinder: sIdLegInputTdm1 = 5562, sIdLegOutputPak1 = 5559, sIdLegInputPak2 = 5560, sIdLegOutputTdm2 = 5561 requesterCallID = 880958 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 5560, callID = 880959, ownerContext = 0x0 resFunc =0x0 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 5559, callID = 880959, ownerContext = 0x0 resFunc =0x0 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = 880959, confID = -1, streamType = PACKET, streamDir = INPUT, ownerContext = 0x40074020, *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: codec = 0x1, fax_modem_type=0, XmitFn = 0x34DBB230, packetStreamSSRC =0x0 , multicastStreamDtmfType = 1 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: set xmit_fn:0x34DBB230 for callID:880959 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 5558 *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = 880959, confID = -1, streamType = PACKET, streamDir = OUTPUT, ownerContext = 0x40074020, *Apr 18 13:21:50.351: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: codec = 0x1, fax_modem_type=0, XmitFn = 0x0, packetStreamSSRC =0x0 , multicastStreamDtmfType = 1 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: set xmit_fn:0x0 callID:880959 encap:1 media:0 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 5557 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpConnectGwBinderReq: sIdLegInputTdm1=5562, sIdLegOutputPak1=5557, sIdLegInputPak2=5558, sIdLegOutputTdm2=5561, Call Id=880958 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: sIdLegInput = 5562, sIdLegOutput = 5561, callID = 880958 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: Copying nominator(0x21ED77A8) to req_data.. *Apr 18 13:21:50.355: Modify Nominator = *Apr 18 13:21:50.355: FAX_TONE_DET *Apr 18 13:21:50.355: MODEM_TONE_DET *Apr 18 13:21:50.355: CM_TONE_DET *Apr 18 13:21:50.355: T30_CM_TONE_DET *Apr 18 13:21:50.355: TTY TONE DETFORKING Parameters are forking mask: 7, simple_forking_codec_mask: 151033983, complex_forking_codec_mask 38927 *Apr 18 13:21:50.355: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_ACK *Apr 18 13:21:50.355: //880958/411D4008A818/CCAPI/cc_api_caps_ack: Destination Interface=0x40B81140, Destination Call Id=880959, Source Call Id=880958, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_14400(0x80), Fax Version:=0, Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=8773) *Apr 18 13:21:50.355: //880958/411D4008A818/CCAPI/cc_process_notify_bridge_done: Conference Id=0x127, Call Id1=880958, Call Id2=880959 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_process_event: No session is associated to the streams. *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_caps: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_packet_stream_caps: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_packet_stream_caps: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps:exit@620 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps:exit@620 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_process_event: No session is associated to the streams. *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_caps: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_packet_stream_caps: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_packet_stream_caps: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps:exit@620 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps:exit@620 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_process_event: No session is associated to the streams. *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_process_event: SOFTWARE_ERROR_INFORMATIONAL; DSMP Session Not Found Event=E_DSMP_CC_DISCONNECT *Apr 18 13:21:50.355: dsmp_process_event: evt->Type = E_DSMP_DELETE_STREAM, sId = 5560, calling dsmp_retrieve_session() *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/remove_stream_from_DB: *Apr 18 13:21:50.355: remove_stream_from_DB:Removing stream w/ ID = 5560 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_process_event: No session is associated to the streams. *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_process_event: SOFTWARE_ERROR_INFORMATIONAL; DSMP Session Not Found Event=E_DSMP_DELETE_STREAM *Apr 18 13:21:50.355: dsmp_process_event: evt->Type = E_DSMP_DELETE_STREAM, sId = 5559, calling dsmp_retrieve_session() *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/remove_stream_from_DB: *Apr 18 13:21:50.355: remove_stream_from_DB:Removing stream w/ ID = 5559 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_process_event: No session is associated to the streams. *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_process_event: SOFTWARE_ERROR_INFORMATIONAL; DSMP Session Not Found Event=E_DSMP_DELETE_STREAM *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5558 is enlisted, total = 3 *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5557 is enlisted, total = 4 *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_CONNECT_REQ] *Apr 18 13:21:50.355: [0x222AAC84]CNFSM: cur_container:dtmf_sig_container, cur_state:S_DSMP_DTSIG_RUNNING, event:E_DSMP_CC_CONNECT_REQ *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_stop_service: *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:21:50.355: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:21:50.355: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_INITING *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_decide_pgm_based_stm: *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_rsc_init_succ: pgm_base_stm_id = 5558, service_id = 5, codec =1 *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP pending *Apr 18 13:21:50.355: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_OPENING *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_rsc_pend: *Apr 18 13:21:50.355: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_RSC_OPENING, event:E_DSMP_CC_MODIFY_REQ] *Apr 18 13:21:50.355: [0x222AAC84]CNFSM: cur_container:rsc_main_container, cur_state:S_DSMP_RSC_OPENING, event:E_DSMP_CC_MODIFY_REQ *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_defer: *Apr 18 13:21:50.355: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_RSC_OPENING, event:E_DSMP_DSPRM_PEND_SUCCESS] *Apr 18 13:21:50.355: [0x222AAC84]CNFSM: cur_container:rsc_main_container, cur_state:S_DSMP_RSC_OPENING, event:E_DSMP_DSPRM_PEND_SUCCESS *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_rsc_open_succ: *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_static_params: *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_static_params: service_type=1 base stream=5558 tdm_caps=0x2251B6FC pkt_caps=0x4135D248 *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_static_params: *Apr 18 13:21:50.355: ip_tones = 0, pstn_tones = 0 *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_static_params: voice detection disabled *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_static_params: silence detection disabled *Apr 18 13:21:50.355: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_static_params: tty detection disabled *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:21:50.355: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_STATIC_CONF *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_start_service: *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:21:50.355: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_STARTING *Apr 18 13:21:50.355: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_act_rsc_start_succ: *Apr 18 13:21:50.359: htsp_process_event: [0/0/6, FXSLS_OFFHOOK, E_HTSP_VOICE_CUT_THROUGH]fxsls_voice_cut_thru *Apr 18 13:21:52.807: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 OK Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK8878F2327 From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:28:49 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 CSeq: 101 INVITE Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence Supported: replaces Server: Cisco-CUCM10.5 Supported: X-cisco-srtp-fallback Supported: Geolocation Session-Expires: 1800;refresher=uas Require: timer P-Asserted-Identity: Remote-Party-ID: ;party=called;screen=yes;privacy=off Contact: Content-Type: application/sdp Content-Length: 253 v=0 o=CiscoSystemsCCM-SIP 604811112 1 IN IP4 10.251.128.131 s=SIP Call c=IN IP4 10.252.255.236 b=TIAS:64000 b=CT:64 b=AS:64 t=0 0 m=audio 19212 RTP/AVP 0 101 a=ptime:20 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 *Apr 18 13:21:52.807: //880959/411D4008A818/CCAPI/cc_api_call_connected: Interface=0x40B81140, Data Bitmask=0x81000001, Progress Indication=NULL(0), Connection Handle=0 *Apr 18 13:21:52.807: //880959/411D4008A818/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) *Apr 18 13:21:52.807: //880958/411D4008A818/CCAPI/ccCallConnect: Progress Indication=NULL(0), Data Bitmask=0x81000001 *Apr 18 13:21:52.807: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_call_connect: Connected Name *Apr 18 13:21:52.807: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_call_connect: Connected Number 3300 *Apr 18 13:21:52.807: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_call_connect: Connected oct3a 81 *Apr 18 13:21:52.807: //880958/411D4008A818/CCAPI/ccCallConnect: Call Entry(Connected=TRUE, Responsed=TRUE) *Apr 18 13:21:52.807: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_ALERTING, event:E_CC_CONNECT] *Apr 18 13:21:52.807: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/act_alert_connect: Progress Indication=0 *Apr 18 13:21:52.807: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: sIdLegInput = 5558, sIdLegOutput = 5557, callID = 880958 *Apr 18 13:21:52.807: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: Copying nominator(0x21ED7DC0) to req_data.. *Apr 18 13:21:52.807: Modify Nominator = *Apr 18 13:21:52.807: PAK_SUPRESS *Apr 18 13:21:52.807: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_ring_noan_timer_stop: Timer Stop Time=-1606407564 *Apr 18 13:21:52.807: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpStopPlay: sIdLegOutput = 5561, callID = 880958, play_type = 6 *Apr 18 13:21:52.807: htsp_process_event: [0/0/6, FXSLS_OFFHOOK, E_HTSP_CONNECT]fxsls_offhook_connect *Apr 18 13:21:52.811: [0/0/6] vg3x0_fxs_set_sig_state: ABCD=6, timestamp=0, sys_time=-1606407564 *Apr 18 13:21:52.811: [0/0/6] set signal state = 0x6 timestamp = 0 *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_MODIFY_REQ] *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_MODIFY_REQ *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_save_modify_caps: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_save_modify_caps: requestCallID= 880958 *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_save_modify_caps: *Apr 18 13:21:52.811: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_packet_stream_caps: *Apr 18 13:21:52.811: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_packet_stream_caps: *Apr 18 13:21:52.811: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_save_modify_tdm_stream_caps:exit@620 *Apr 18 13:21:52.811: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_save_modify_tdm_stream_caps:exit@620 *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_save_stream_detectors: *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: history stored state: S_DSMP_VC_RUNNING, container: simple_voice_container *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: updated current container: rsc_running_container *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: next_state:S_DSMP_MODIFY_CAPS_SAVED *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_static_params_changed: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_dynamic_params_changed: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_modify_to_modem_passthru: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_dynamic_params_changed: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_dynamic_params: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_dynamic_params: servic_type=1 base stream=5558 *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_tty_params: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_tty_params: base stream=5558 pkt_caps=0x4135D248 *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_dynamic_params: tty params conf failed *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_check_and_start_cpa: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_check_and_start_cpa: base stream=5558 *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_detector_params: *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: restoring history state: S_DSMP_VC_RUNNING *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: restoring history container: simple_voice_container *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: new_container:simple_voice_container *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: next_state:S_DSMP_VC_RUNNING *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ] *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_stop_call_waiting_play_allowed: *Apr 18 13:21:52.811: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_stateless_tone_cleanup: *Apr 18 13:21:52.811: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:52.811: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Sent: ACK sip:87813300@10.251.128.131:5060 SIP/2.0 Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK887901FBD From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:21:50 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: telephone-event Content-Length: 0 *Apr 18 13:21:55.667: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:55.667: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:55.667: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:55.667: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:55.667: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:55.667: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:55.667: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:55.667: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:55.667: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:55.667: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:55.667: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:55.667: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:55.667: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:55.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:55.835: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:55.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:55.835: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:55.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:55.835: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:55.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:55.835: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:55.835: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:55.835: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:55.835: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:55.835: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:55.835: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:56.007: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:56.007: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:56.007: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.007: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.007: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:56.007: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.007: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.007: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:56.007: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:56.007: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.007: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:56.007: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.007: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:56.163: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:56.163: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:56.163: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.163: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.163: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:56.163: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.163: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.163: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:56.163: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:56.163: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.163: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:56.163: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.163: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:56.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:56.335: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:56.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.335: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:56.335: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.335: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:56.335: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:56.335: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.335: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:56.335: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.335: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:56.503: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:56.503: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:56.503: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.503: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.503: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:56.503: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.503: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.503: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:56.503: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:56.503: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.503: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:56.503: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.503: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:56.663: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:56.663: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:56.663: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.663: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.663: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:56.663: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.663: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.663: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:56.663: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:56.663: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.663: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:56.663: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.667: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:56.831: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:56.831: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:56.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.835: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:56.835: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:56.835: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:56.835: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:56.835: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:56.835: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:56.835: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:56.835: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:57.007: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:57.007: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:57.007: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.007: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.007: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:57.007: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.007: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.007: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:57.007: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:57.007: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.007: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:57.007: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.007: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:57.167: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:57.167: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:57.167: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.167: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.167: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:57.167: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.167: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.167: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:57.167: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:57.167: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.167: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:57.167: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.167: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:57.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:57.335: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:57.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.335: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:57.335: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.335: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:57.335: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:57.335: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.335: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:57.335: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.335: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:57.503: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:57.503: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:57.503: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.503: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.503: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:57.503: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.503: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.503: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:57.503: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:57.503: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.503: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:57.503: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.503: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:57.663: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:57.663: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:57.663: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.663: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.663: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:57.663: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.663: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.663: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:57.663: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:57.663: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.663: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:57.663: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.663: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:57.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:57.835: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:57.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.835: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:57.835: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:57.835: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:57.835: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:57.835: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:57.835: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:57.835: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:57.835: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:58.003: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:58.003: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:58.003: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:58.003: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:58.003: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:58.003: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:58.003: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:58.003: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:58.003: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:58.003: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:58.003: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:58.003: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:58.003: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:58.163: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:58.163: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:58.163: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:58.163: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:58.163: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:58.163: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:58.163: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:58.163: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:58.163: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:58.167: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:58.167: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:58.167: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:58.167: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:58.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:58.335: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:58.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:58.335: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:58.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:58.335: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:58.335: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:58.335: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:58.335: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:58.335: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:58.335: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:58.335: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:58.335: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:58.507: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:58.507: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:58.507: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:58.507: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:58.507: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:58.507: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:58.507: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:58.507: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:58.507: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:58.507: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:58.507: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:58.507: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:58.507: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:21:58.667: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE] *Apr 18 13:21:58.667: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_CM_TONE *Apr 18 13:21:58.667: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:58.667: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:58.667: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM] *Apr 18 13:21:58.667: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:58.667: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:21:58.667: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:21:58.667: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_T30_CM_TONE_TDM, callback = 0x34871BE4 *Apr 18 13:21:58.667: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_T30_CM_TONE_TDM *Apr 18 13:21:58.667: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:21:58.667: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:21:58.667: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:22:11.883: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_FAX_TONE] *Apr 18 13:22:11.883: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DSP_FAX_TONE *Apr 18 13:22:11.883: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_general_tone_detected: *Apr 18 13:22:11.883: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: *Apr 18 13:22:11.883: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_detection_cb_helper: stream id = 5562, streamTag = 0, event = E_DSMP_DSP_FAX_TONE, callback = 0x34871BE4 *Apr 18 13:22:11.883: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_FAX_TONE *Apr 18 13:22:11.883: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:22:11.883: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_MC_START *Apr 18 13:22:11.883: //880958/411D4008A818/CCAPI/cc_api_t38_fax_start: Destination Interface=0x40B81140, Destination Call Id=880959, Source Call Id=880958, Caps(Codec=T38Fax(0x10000), Fax Rate=FAX_RATE_14400(0x80),Fax Version:=0, Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=160, Signal Type=2) *Apr 18 13:22:11.883: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_media_event_cb: Event=E_DSMP_DSP_FAX_TONE *Apr 18 13:22:11.883: //880958/411D4008A818/CCAPI/cc_api_call_feature: Feature Type=1, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:22:11.883: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:11.883: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:22:11.887: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Sent: INVITE sip:87813300@10.251.128.131:5060 SIP/2.0 Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK887911484 Remote-Party-ID: ;party=calling;screen=no;privacy=off From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:22:11 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 Supported: 100rel,timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Cisco-Guid: 1092435976-1111626216-2820204872-2965090818 User-Agent: Cisco-SIPGateway/IOS-15.4.3.M2 Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 102 INVITE Max-Forwards: 70 Timestamp: 1524057731 Contact: Expires: 86 Allow-Events: telephone-event Session-Expires: 1800;refresher=uas Content-Type: application/sdp Content-Length: 324 v=0 o=CiscoSystemsSIP-GW-UserAgent 4691 9272 IN IP4 172.23.199.166 s=SIP Call c=IN IP4 172.23.199.166 t=0 0 m=image 20192 udptl t38 c=IN IP4 172.23.199.166 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:200 a=T38FaxMaxDatagram:320 a=T38FaxUdpEC:t38UDPRedundancy *Apr 18 13:22:11.887: //880959/411D4008A818/CCAPI/ccCallFeature: Feature Type=1, Call Id=880959 *Apr 18 13:22:11.891: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK887911484 From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:29:11 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 CSeq: 102 INVITE Allow-Events: presence Server: Cisco-CUCM10.5 Content-Length: 0 *Apr 18 13:22:11.891: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 488 Not Acceptable Media Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK887911484 From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:29:11 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 CSeq: 102 INVITE Allow-Events: presence Server: Cisco-CUCM10.5 P-Asserted-Identity: Remote-Party-ID: ;party=called;screen=yes;privacy=off Contact: Content-Length: 0 *Apr 18 13:22:11.891: //880958/411D4008A818/CCAPI/cc_api_caps_nak: Destination Interface=0x39B07D64, Destination Call Id=880958, Source Call Id=880959, Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2, Modem=0x0, Codec Bytes=20, Signal Type=2) *Apr 18 13:22:11.891: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_FAX_PTHRU_START *Apr 18 13:22:11.891: //880959/411D4008A818/CCAPI/cc_api_fax_passthrough_start: Destination Interface=0x40B81140, Destination Call Id=880959, Source Call Id=880958, Caps(Codec=g711ulaw(0x1), Fax Rate=Invalid(0x0), Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=160, Signal Type=0) *Apr 18 13:22:11.891: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Sent: ACK sip:87813300@10.251.128.131:5060 SIP/2.0 Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK887911484 From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:22:11 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 Max-Forwards: 70 CSeq: 102 ACK Allow-Events: telephone-event Content-Length: 0 *Apr 18 13:22:11.895: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Sent: INVITE sip:87813300@10.251.128.131:5060 SIP/2.0 Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK88792928 Remote-Party-ID: ;party=calling;screen=no;privacy=off From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:22:11 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 Supported: 100rel,timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Cisco-Guid: 1092435976-1111626216-2820204872-2965090818 User-Agent: Cisco-SIPGateway/IOS-15.4.3.M2 Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 103 INVITE Max-Forwards: 70 Timestamp: 1524057731 Contact: Expires: 86 Allow-Events: telephone-event Session-Expires: 1800;refresher=uas Content-Type: application/sdp Content-Length: 187 v=0 o=CiscoSystemsSIP-GW-UserAgent 4691 9273 IN IP4 172.23.199.166 s=SIP Call c=IN IP4 172.23.199.166 t=0 0 m=audio 20192 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - *Apr 18 13:22:11.895: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK88792928 From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:29:11 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 CSeq: 103 INVITE Allow-Events: presence Server: Cisco-CUCM10.5 Content-Length: 0 *Apr 18 13:22:11.899: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 OK Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK88792928 From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:29:11 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 CSeq: 103 INVITE Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence Supported: replaces Server: Cisco-CUCM10.5 Supported: X-cisco-srtp-fallback Supported: Geolocation Session-Expires: 1800;refresher=uas Require: timer P-Asserted-Identity: Remote-Party-ID: ;party=called;screen=yes;privacy=off Contact: Content-Type: application/sdp Content-Length: 253 v=0 o=CiscoSystemsCCM-SIP 604811112 2 IN IP4 10.251.128.131 s=SIP Call c=IN IP4 10.252.255.236 b=TIAS:64000 b=CT:64 b=AS:64 t=0 0 m=audio 19212 RTP/AVP 0 101 a=ptime:20 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 5558, callID = 880958, mode = 3 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 5558, callID = 880958, mode = 3 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 5558, callID = 880958, mode = 3 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 5558, callID = 880958, mode = 3 *Apr 18 13:22:11.899: //880958/411D4008A818/CCAPI/cc_api_fax_passthrough_start: Destination Interface=0x39B07D64, Destination Call Id=880958, Source Call Id=880959, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_14400(0x80), Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=160, Signal Type=2) *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDisconnectGwBinder: sIdLegInputTdm1 = 5562, sIdLegOutputPak1 = 5557, sIdLegInputPak2 = 5558, sIdLegOutputTdm2 = 5561 requesterCallID = 880958 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = 880959, confID = -1, streamType = PACKET, streamDir = INPUT, ownerContext = 0x40074020, *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: codec = 0x1, fax_modem_type=10, XmitFn = 0x34DBB230, packetStreamSSRC =0x0 , multicastStreamDtmfType = 0 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: set xmit_fn:0x34DBB230 for callID:880959 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 5556 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = 880959, confID = -1, streamType = PACKET, streamDir = OUTPUT, ownerContext = 0x40074020, *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: codec = 0x1, fax_modem_type=10, XmitFn = 0x3488CFE8, packetStreamSSRC =0x0 , multicastStreamDtmfType = 0 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: set xmit_fn:0x3488CFE8 callID:880959 encap:1 media:0 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 5555 *Apr 18 13:22:11.899: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpConnectGwBinderReq: sIdLegInputTdm1=5562, sIdLegOutputPak1=5555, sIdLegInputPak2=5556, sIdLegOutputTdm2=5561, Call Id=880958 *Apr 18 13:22:11.899: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_DISCONNECT] *Apr 18 13:22:11.899: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_DISCONNECT *Apr 18 13:22:11.899: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:22:11.899: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_cc_disconnect: *Apr 18 13:22:11.899: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_connection: Connection is delisted, delisted LegIn: 5562, delisted LegOut: 5557, total conn_count is = 1 *Apr 18 13:22:11.899: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_connection: Connection is delisted, delisted LegIn: 5558, delisted LegOut: 5561, total conn_count is = 0 *Apr 18 13:22:11.903: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5562 already exists in the session *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5561 already exists in the session *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5556 is enlisted, total = 5 *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_stream: Stream 5555 is enlisted, total = 6 *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_CONNECT_REQ] *Apr 18 13:22:11.903: [0x222AAC84]CNFSM: cur_container:simple_voice_container, cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_CONNECT_REQ *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_new_connect_req: *Apr 18 13:22:11.903: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_connect_rtp_modem_passthru: *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_get_stm_service_type: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_connect_aal2_modem_passthru: *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_get_stm_service_type: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_connect_fax_passthru: *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_get_stm_service_type: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/is_programed_voice_or_vbd: *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_get_stm_service_type: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_fax_passthru: *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: *Apr 18 13:22:11.903: [0x222AAC84]CNFSM: history stored state: S_DSMP_VC_RUNNING, container: simple_voice_container *Apr 18 13:22:11.903: [0x222AAC84]CNFSM: updated current container: rsc_running_container *Apr 18 13:22:11.903: [0x222AAC84]CNFSM: new_container:fax_passthru_container *Apr 18 13:22:11.903: [0x222AAC84]CNFSM: next_state:S_DSMP_GW_FVBD_INIT *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_is_pgm_base_stm_vbd: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_is_pgm_base_stm_vbd:exit@2399 *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_is_not_pgm_base_stm_vbd: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_is_pgm_base_stm_vbd: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_is_pgm_base_stm_vbd:exit@2399 *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_fvbd_act_init: *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_decide_pgm_based_stm: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_fvbd_act_init: mode 2, inital 100, min 40, max 200, fax_nom 100 *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_turn_on_nr: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_turn_on_nr: turn off tdm nr tag 0 *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_turn_on_nr: turn off packet nr tag 0 *Apr 18 13:22:11.903: [0x222AAC84]CNFSM: next_state:S_DSMP_GW_FVBD_STARTING *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_fvbd_act_start_succ: *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_connect_req_done: *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_connection: Connection is added, enlisted LegIn: 5562, enlisted LegOut: 5555 *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_enlist_connection: Connection is added, enlisted LegIn: 5556, enlisted LegOut: 5561 *Apr 18 13:22:11.903: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_MODIFY_MEDIA_IND *Apr 18 13:22:11.903: //880958/411D4008A818/CCAPI/cc_api_modify_media_ind: IFType=0x3, Source Call Id=0xD713E, Destination Call Id=0xD713F, Codec=1, Codec Bytes=160 *Apr 18 13:22:11.903: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_conf_detector_params: *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_detector_params: fax: 1 *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_detector_params: fax cm suppression is set *Apr 18 13:22:11.903: [0x222AAC84]CNFSM: next_state:S_DSMP_GW_FVBD_RUNNING *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 5556, callID = 880958, mode = 3 *Apr 18 13:22:11.903: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 5556, callID = 880958, mode = 3 *Apr 18 13:22:11.903: //880959/411D4008A818/CCAPI/cc_api_modify_media_ack: IFType=0x6, Source Call Id=0xD713F, Destination Call Id=0xD713E *Apr 18 13:22:11.903: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Sent: ACK sip:87813300@10.251.128.131:5060 SIP/2.0 Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK887931BD1 From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:22:11 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 Max-Forwards: 70 CSeq: 103 ACK Allow-Events: telephone-event Content-Length: 0 *Apr 18 13:22:16.675: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS] *Apr 18 13:22:16.675: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS *Apr 18 13:22:16.675: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:16.675: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:19.195: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS] *Apr 18 13:22:19.195: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS *Apr 18 13:22:19.195: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:19.195: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:19.455: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE] *Apr 18 13:22:19.455: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE *Apr 18 13:22:19.455: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:19.455: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:24.235: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: OPTIONS sip:172.23.199.166:5060 SIP/2.0 Via: SIP/2.0/TCP 10.252.128.131:5060;branch=z9hG4bK128b5dc309cd017 From: ;tag=2145968143 To: Date: Wed, 18 Apr 2018 13:29:23 GMT Call-ID: 80834100-ad714833-108b308-8380fc0a@10.252.128.131 User-Agent: Cisco-CUCM10.5 CSeq: 101 OPTIONS Contact: Max-Forwards: 0 Content-Length: 0 *Apr 18 13:22:24.239: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.252.128.131:5060;branch=z9hG4bK128b5dc309cd017 From: ;tag=2145968143 To: ;tag=4282234C-16E6 Date: Wed, 18 Apr 2018 13:22:24 GMT Call-ID: 80834100-ad714833-108b308-8380fc0a@10.252.128.131 Server: Cisco-SIPGateway/IOS-15.4.3.M2 CSeq: 101 OPTIONS Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Allow-Events: telephone-event Accept: application/sdp Supported: 100rel,timer,resource-priority,replaces,sdp-anat Content-Type: application/sdp Content-Length: 173 v=0 o=CiscoSystemsSIP-GW-UserAgent 4221 9751 IN IP4 172.23.199.166 s=SIP Call c=IN IP4 172.23.199.166 t=0 0 m=audio 0 RTP/AVP 18 0 8 9 4 2 15 c=IN IP4 172.23.199.166 *Apr 18 13:22:26.787: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS] *Apr 18 13:22:26.787: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS *Apr 18 13:22:26.787: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:26.787: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:27.043: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE] *Apr 18 13:22:27.043: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE *Apr 18 13:22:27.043: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:27.043: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:32.103: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS] *Apr 18 13:22:32.103: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS *Apr 18 13:22:32.103: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:32.103: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:34.643: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS] *Apr 18 13:22:34.643: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS *Apr 18 13:22:34.643: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:34.643: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:34.863: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE] *Apr 18 13:22:34.863: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE *Apr 18 13:22:34.863: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:34.863: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:39.935: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS] *Apr 18 13:22:39.935: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS *Apr 18 13:22:39.935: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:39.935: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:41.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS] *Apr 18 13:22:41.835: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS *Apr 18 13:22:41.835: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:41.835: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:42.035: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE] *Apr 18 13:22:42.035: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE *Apr 18 13:22:42.035: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:42.035: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:45.183: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: OPTIONS sip:172.23.199.166:5060 SIP/2.0 Via: SIP/2.0/TCP 10.251.128.52:5060;branch=z9hG4bK25878a251e9b62 From: ;tag=663279124 To: Date: Wed, 18 Apr 2018 13:29:44 GMT Call-ID: 8d079980-ad714848-24e46b-3480fb0a@10.251.128.52 User-Agent: Cisco-CUCM8.6 CSeq: 101 OPTIONS Contact: Max-Forwards: 0 Content-Length: 0 *Apr 18 13:22:45.187: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.251.128.52:5060;branch=z9hG4bK25878a251e9b62 From: ;tag=663279124 To: ;tag=42827520-19E1 Date: Wed, 18 Apr 2018 13:22:45 GMT Call-ID: 8d079980-ad714848-24e46b-3480fb0a@10.251.128.52 Server: Cisco-SIPGateway/IOS-15.4.3.M2 CSeq: 101 OPTIONS Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Allow-Events: telephone-event Accept: application/sdp Supported: 100rel,timer,resource-priority,replaces,sdp-anat Content-Type: application/sdp Content-Length: 173 v=0 o=CiscoSystemsSIP-GW-UserAgent 6870 6149 IN IP4 172.23.199.166 s=SIP Call c=IN IP4 172.23.199.166 t=0 0 m=audio 0 RTP/AVP 18 0 8 9 4 2 15 c=IN IP4 172.23.199.166 *Apr 18 13:22:47.083: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS] *Apr 18 13:22:47.083: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS *Apr 18 13:22:47.083: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:47.083: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:49.995: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE] *Apr 18 13:22:49.995: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE *Apr 18 13:22:49.995: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:49.995: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:55.055: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS] *Apr 18 13:22:55.055: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS *Apr 18 13:22:55.055: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:55.055: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:22:57.963: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE] *Apr 18 13:22:57.963: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE *Apr 18 13:22:57.963: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:22:57.963: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:03.023: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS] *Apr 18 13:23:03.023: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_SILENCE_250MS *Apr 18 13:23:03.023: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:23:03.023: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:05.923: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE] *Apr 18 13:23:05.923: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DSP_FAX_TONE *Apr 18 13:23:05.923: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:23:05.923: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:06.983: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=55278 systime=-1606400147 *Apr 18 13:23:06.983: htsp_process_event: [0/0/6, FXSLS_CONNECT, E_DSP_SIG_0100]fxsls_offhook_onhook, HF duration Max=200, HF duration Min=75 *Apr 18 13:23:06.983: htsp_timer - 200 msec *Apr 18 13:23:07.183: htsp_process_event: [0/0/6, FXSLS_CONNECT, E_HTSP_EVENT_TIMER]fxsls_connect_wait_release_req *Apr 18 13:23:07.183: htsp_timer_stop *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_CONNECT, event:E_TSP_DISCONNECT_IND] *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/act_generate_disc: Cause Value=16 *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606400127 *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_ring_noan_timer_stop: Timer Stop Time=-1606400127 *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_set_release_source: Release Direction=PSTN, Release Source=Calling Party-PSTN *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_cc_call_disconnected: *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_cc_call_disconnected: Cause Value=16 *Apr 18 13:23:07.183: //880958/411D4008A818/CCAPI/cc_api_call_disconnected: Cause Value=16, Interface=0x39B07D64, Call Id=880958 *Apr 18 13:23:07.183: //880958/411D4008A818/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0) *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer: Timer Start Time=-1606400127, Timer Value=15000(ms) *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/act_generate_disc: Return Code=0 *Apr 18 13:23:07.183: //880958/411D4008A818/CCAPI/ccConferenceDestroy: Conference Id=0x127, Tag=0x0 *Apr 18 13:23:07.183: //880958/411D4008A818/CCAPI/ccConferenceDestroy: *Apr 18 13:23:07.183: confID:0x127; callEntry1 callID1:0xD713E, type:6; callEntry2 callID2:0xD713F, type:3 *Apr 18 13:23:07.183: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDisconnectGwBinder: sIdLegInputTdm1 = 5562, sIdLegOutputPak1 = 5555, sIdLegInputPak2 = 5556, sIdLegOutputTdm2 = 5561 requesterCallID = 880958 *Apr 18 13:23:07.183: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 5556, callID = 880959, ownerContext = 0x0 resFunc =0x0 *Apr 18 13:23:07.183: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 5555, callID = 880959, ownerContext = 0x0 resFunc =0x0 *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_bridge_status_cb: *Apr 18 13:23:07.183: //880958/411D4008A818/CCAPI/cc_api_bridge_drop_done: Conference Id=0x127, Source Interface=0x39B07D64, Source Call Id=880958, Destination Call Id=880959, Disposition=0x0, Tag=0x0 *Apr 18 13:23:07.183: //880959/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: *Apr 18 13:23:07.183: cc_api_get_xcode_stream : 4982 *Apr 18 13:23:07.183: //880958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: *Apr 18 13:23:07.183: cc_api_get_xcode_stream : 4982 *Apr 18 13:23:07.183: //880959/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: *Apr 18 13:23:07.183: cc_api_get_xcode_stream : 4982 *Apr 18 13:23:07.183: //880959/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: *Apr 18 13:23:07.183: cc_api_get_xcode_stream : 4982 *Apr 18 13:23:07.183: //880959/411D4008A818/CCAPI/cc_api_bridge_drop_done: Conference Id=0x127, Source Interface=0x40B81140, Source Call Id=880959, Destination Call Id=880958, Disposition=0x0, Tag=0x0 *Apr 18 13:23:07.183: //880958/411D4008A818/CCAPI/cc_generic_bridge_done: Conference Id=0x127, Source Interface=0x40B81140, Source Call Id=880959, Destination Call Id=880958, Disposition=0x0, Tag=0x0 *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_CC_DISCONNECT] *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_CC_DISCONNECT *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_cc_disconnect: *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_connection: Connection is delisted, delisted LegIn: 5562, delisted LegOut: 5555, total conn_count is = 1 *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_connection: Connection is delisted, delisted LegIn: 5556, delisted LegOut: 5561, total conn_count is = 0 *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:07.183: dsmp_process_event: evt->Type = E_DSMP_DELETE_STREAM, sId = 5556, calling dsmp_retrieve_session() *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM] *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_not_last_stream: *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_last_stream: *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_not_last_stream: *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream ID=5556 *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream Count=5 *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/remove_stream_from_DB: *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/process_software_multicast_streams: *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:07.183: dsmp_process_event: evt->Type = E_DSMP_DELETE_STREAM, sId = 5555, calling dsmp_retrieve_session() *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM] *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_not_last_stream: *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_last_stream: *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_not_last_stream: *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream ID=5555 *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream Count=4 *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/remove_stream_from_DB: *Apr 18 13:23:07.183: //0x222AAC84/411D4008A818/DSMP:():-1/process_software_multicast_streams: *Apr 18 13:23:07.183: remove_stream_from_DB:Removing stream w/ ID = 5555 *Apr 18 13:23:07.183: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:07.183: //880958/411D4008A818/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16) *Apr 18 13:23:07.183: //880958/411D4008A818/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) *Apr 18 13:23:07.183: //880958/411D4008A818/CCAPI/cc_api_get_transfer_info: Transfer Number=NULL *Apr 18 13:23:07.183: //880959/411D4008A818/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) *Apr 18 13:23:07.183: //880959/411D4008A818/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_CONNECT, event:E_CC_DISCONNECT] *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/act_disconnect: Cause Value=16, Previous Cause Value=16 *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_ring_noan_timer_stop: Timer Stop Time=-1606400127 *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_pcm_cap_conf_tone_timer_stop: Timer Stop Time=-1606400127 *Apr 18 13:23:07.183: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_save_fax_config: *Apr 18 13:23:07.187: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=T38_FAX_RELAY, Fallback Fax Protocol=FAXPASSTHROUGH_FAX Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=Dialpeer, Peer=100 *Apr 18 13:23:07.187: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_save_fax_config: Fax Version:=0, LS Red=4, HS Red=1 *Apr 18 13:23:07.187: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_save_fax_config: Passthrough Codec=g711ulaw *Apr 18 13:23:07.187: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_set_fax_feat_param: *Apr 18 13:23:07.187: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpStopPlay: sIdLegOutput = 5561, callID = 880958, play_type = 6 *Apr 18 13:23:07.187: htsp_timer_stop3 *Apr 18 13:23:07.187: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_duplicate_stats: *Apr 18 13:23:07.187: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_COLLECTING_STATS, event:E_DSMP_CC_STOP_PLAY_REQ] *Apr 18 13:23:07.187: [0x222AAC84]CNFSM: cur_container:rsc_running_container, cur_state:S_DSMP_COLLECTING_STATS, event:E_DSMP_CC_STOP_PLAY_REQ *Apr 18 13:23:07.187: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:23:07.187: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_defer: *Apr 18 13:23:07.187: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:07.187: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_duplicate_stats: *Apr 18 13:23:07.199: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_COLLECTING_STATS, event:E_DSMP_CC_STOP_PLAY_REQ] *Apr 18 13:23:07.199: [0x222AAC84]CNFSM: cur_container:rsc_running_container, cur_state:S_DSMP_COLLECTING_STATS, event:E_DSMP_CC_STOP_PLAY_REQ *Apr 18 13:23:07.199: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:23:07.199: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_defer: *Apr 18 13:23:07.199: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:07.199: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_duplicate_stats: *Apr 18 13:23:07.199: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Sent: BYE sip:87813300@10.251.128.131:5060 SIP/2.0 Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK887962574 From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:22:11 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 User-Agent: Cisco-SIPGateway/IOS-15.4.3.M2 Max-Forwards: 70 Timestamp: 1524057787 CSeq: 104 BYE Reason: Q.850;cause=16 P-RTP-Stat: PS=3840,OS=614400,PR=3851,OR=614600,PL=0,JI=2,LA=0,DU=74 Content-Length: 0 *Apr 18 13:23:07.203: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_dsm_get_levels_done_cb: *Apr 18 13:23:07.203: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ] *Apr 18 13:23:07.203: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ *Apr 18 13:23:07.203: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:23:07.203: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:23:07.203: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:23:07.203: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:07.207: //880959/411D4008A818/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 OK Via: SIP/2.0/UDP 172.23.199.166:5060;branch=z9hG4bK887962574 From: ;tag=42819E2C-A20 To: ;tag=604811112~0714bd78-bf46-4df3-b0bb-ef559c54cf09-156136664 Date: Wed, 18 Apr 2018 13:30:06 GMT Call-ID: 4966D4E7-424211E8-A81DE948-B0BBB202@172.23.199.166 Server: Cisco-CUCM10.5 CSeq: 104 BYE Content-Length: 0 *Apr 18 13:23:07.207: //880959/411D4008A818/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x40B81140, Tag=0x0, Call Id=880959, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) *Apr 18 13:23:07.207: //880959/411D4008A818/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Apr 18 13:23:07.211: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Apr 18 13:23:07.211: :cc_free_feature_vsa freeing 2187D530 *Apr 18 13:23:07.211: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Apr 18 13:23:07.211: vsacount in free is 1 *Apr 18 13:23:07.215: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE] *Apr 18 13:23:07.215: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/act_dsm_dsp_stats_complete: *Apr 18 13:23:07.215: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer: Timer Start Time=-1606400124, Timer Value=60000(ms) *Apr 18 13:23:07.215: htsp_process_event: [0/0/6, FXSLS_WAIT_RELEASE_REQ, E_HTSP_RELEASE_REQ]fxsls_waitrls_req_rls *Apr 18 13:23:07.215: [0/0/6] vg3x0_fxs_set_sig_state: ABCD=4, timestamp=0, sys_time=-1606400124 *Apr 18 13:23:07.215: [0/0/6] set signal state = 0x4 timestamp = 0 *Apr 18 13:23:07.215: [0/0/6] vg3x0_fxs_set_sig_state: ABCD=4, timestamp=0, sys_time=-1606400124 *Apr 18 13:23:07.215: [0/0/6] set signal state = 0x4 timestamp = 0 *Apr 18 13:23:07.215: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_process_event: [state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF] *Apr 18 13:23:07.215: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/act_wrelease_release: *Apr 18 13:23:07.215: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_play_busy_timer_stop: Timer Stop Time=-1606400124 *Apr 18 13:23:07.215: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_timer_stop: Timer Stop Time=-1606400124 *Apr 18 13:23:07.215: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_do_call_history: *Apr 18 13:23:07.219: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_do_call_history: Coder Rate=5 *Apr 18 13:23:07.219: //880958/411D4008A818/VTSP:(0/0/6):-1:1:1/vtsp_is_valid_dsm_handle: DMGR=0x4007379C, VTSP CDB=0x226F1F88 *Apr 18 13:23:07.219: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpStopPlay: sIdLegOutput = 5561, callID = 880958, play_type = 6 *Apr 18 13:23:07.219: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 5562, callID = 880958, ownerContext = 0x194 resFunc =0x0 *Apr 18 13:23:07.219: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 5561, callID = 880958, ownerContext = 0x194 resFunc =0x0 *Apr 18 13:23:07.219: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 5558, callID = 880959, ownerContext = 0x194 resFunc =0x0 *Apr 18 13:23:07.219: //0xFFFFFFFF/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 5557, callID = 880959, ownerContext = 0x194 resFunc =0x0 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ] *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_ignore: *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:07.219: dsmp_process_event: evt->Type = E_DSMP_DELETE_STREAM, sId = 5562, calling dsmp_retrieve_session() *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM] *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_not_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_not_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream ID=5562 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream Count=3 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/remove_stream_from_DB: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/process_software_multicast_streams: *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:07.219: dsmp_process_event: evt->Type = E_DSMP_DELETE_STREAM, sId = 5561, calling dsmp_retrieve_session() *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM] *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_not_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_not_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream ID=5561 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream Count=2 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/remove_stream_from_DB: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/process_software_multicast_streams: *Apr 18 13:23:07.219: remove_stream_from_DB:Removing stream w/ ID = 5561 *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:07.219: dsmp_process_event: evt->Type = E_DSMP_DELETE_STREAM, sId = 5558, calling dsmp_retrieve_session() *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM] *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_not_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_not_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream ID=5558 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream Count=1 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/remove_stream_from_DB: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/process_software_multicast_streams: *Apr 18 13:23:07.219: remove_stream_from_DB:Removing stream w/ ID = 5558 *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: next_state:CNFSM_NO_STATE_CHANGE *Apr 18 13:23:07.219: dsmp_process_event: evt->Type = E_DSMP_DELETE_STREAM, sId = 5557, calling dsmp_retrieve_session() *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM] *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: cur_container:fax_passthru_container, cur_state:S_DSMP_GW_FVBD_RUNNING, event:E_DSMP_DELETE_STREAM *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_running_container *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_main_container *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_not_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_is_last_stream: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_rsc_stop: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream ID=5557 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_delist_stream: Stream Count=0 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/remove_stream_from_DB: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/process_software_multicast_streams: *Apr 18 13:23:07.219: remove_stream_from_DB:Removing stream w/ ID = 5557 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: history stored state: S_DSMP_GW_FVBD_RUNNING, container: fax_passthru_container *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: updated current container: rsc_main_container *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: new_container:rsc_closing_container *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: next_state:S_DSMP_RSC_STOPPING *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_rsc_stopping_close: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_set_state_var: Message to DSP is successful *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: next_state:S_DSMP_DSPMGR_CLOSING *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_exec: [state:S_DSMP_DSPMGR_CLOSING, event:E_DSMP_DSPRM_CLOSE_COMPLETE] *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: cur_container:rsc_closing_container, cur_state:S_DSMP_DSPMGR_CLOSING, event:E_DSMP_DSPRM_CLOSE_COMPLETE *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_gw_act_dspmgr_closing_complete: *Apr 18 13:23:07.219: //880958/411D4008A818/VTSP:(0/0/6):-1:-1:-1/vtsp_dsm_closed_cb: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/remove_stream_from_DB: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/process_software_multicast_streams: *Apr 18 13:23:07.219: remove_stream_from_DB:Removing stream w/ ID = 5562 *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/remove_stream_from_DB: *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/process_software_multicast_streams: *Apr 18 13:23:07.219: remove_stream_from_DB:Removing stream w/ ID = 5556 *Apr 18 13:23:07.219: [0x222AAC84]CNFSM: next_state:CNFSM_NULL_STATE *Apr 18 13:23:07.219: //0x222AAC84/411D4008A818/DSMP:():-1/dsmp_free_session: *Apr 18 13:23:07.219: //880958/411D4008A818/VTSP:(0/0/6):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] *Apr 18 13:23:07.219: //880958/411D4008A818/VTSP:(0/0/6):-1:-1:-1/act_terminate: *Apr 18 13:23:07.219: //880958/411D4008A818/VTSP:(0/0/6):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=-1606400123 *Apr 18 13:23:07.219: //880958/411D4008A818/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x39B07D64, Tag=0x0, Call Id=880958, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) *Apr 18 13:23:07.219: //880958/411D4008A818/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Apr 18 13:23:07.219: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Apr 18 13:23:07.219: :cc_free_feature_vsa freeing 2187DDF0 *Apr 18 13:23:07.219: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Apr 18 13:23:07.219: vsacount in free is 0 *Apr 18 13:23:07.219: //-1/411D4008A818/VTSP:(0/0/6):-1:-1:-1/vtsp_free_cdb: CDB=0x226F1F88 *Apr 18 13:23:07.219: htsp_process_event: [0/0/6, FXSLS_ONHOOK, E_DSP_SIG_0100]fxsls_onhook_onhook