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

Fax failed with ECM error

deepak.sin
Level 1
Level 1

‎04-18-2018 08:48 AM - edited ‎03-17-2019 12:38 PM

*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: <sip:+441438551097@172.23.199.166>;party=calling;screen=no;privacy=off
From: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>
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: <sip:+441438551097@172.23.199.166:5060>
Call-Info: <sip:172.23.199.166:5060>;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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>
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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:3300@10.251.128.131>
Remote-Party-ID: <sip:3300@10.251.128.131>;party=called;screen=yes;privacy=off
Contact: <sip:87813300@10.251.128.131:5060>
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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:3300@10.251.128.131>
Remote-Party-ID: <sip:3300@10.251.128.131>;party=called;screen=yes;privacy=off
Contact: <sip:87813300@10.251.128.131:5060>
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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:+441438551097@172.23.199.166>;party=calling;screen=no;privacy=off
From: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:+441438551097@172.23.199.166:5060>
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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:3300@10.251.128.131>
Remote-Party-ID: <sip:3300@10.251.128.131>;party=called;screen=yes;privacy=off
Contact: <sip:87813300@10.251.128.131:5060>
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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:+441438551097@172.23.199.166>;party=calling;screen=no;privacy=off
From: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:+441438551097@172.23.199.166:5060>
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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:3300@10.251.128.131>
Remote-Party-ID: <sip:3300@10.251.128.131>;party=called;screen=yes;privacy=off
Contact: <sip:87813300@10.251.128.131:5060>
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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:10.252.128.131>;tag=2145968143
To: <sip:172.23.199.166>
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: <sip:10.252.128.131:5060;transport=tcp>
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: <sip:10.252.128.131>;tag=2145968143
To: <sip:172.23.199.166>;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: <sip:10.251.128.52>;tag=663279124
To: <sip:172.23.199.166>
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: <sip:10.251.128.52:5060;transport=tcp>
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: <sip:10.251.128.52>;tag=663279124
To: <sip:172.23.199.166>;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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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

Labels:
Preview file
170 KB
0 Helpful
1 Reply 1

R0g22
Cisco Employee
Cisco Employee

‎04-18-2018 09:27 AM

What is the fax call flow ? I see T.38 being rejected as below by "172.23.199.166" -

*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: <sip:+441438551097@172.23.199.166>;tag=42819E2C-A20
To: <sip:87813300@10.251.128.131>;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: <sip:3300@10.251.128.131>
Remote-Party-ID: <sip:3300@10.251.128.131>;party=called;screen=yes;privacy=off
Contact: <sip:87813300@10.251.128.131:5060>
Content-Length: 0

And then falling back to pass-through which looks to be successful. Need more information on how the faxes are routing, what devices are involved etc.

0 Helpful
Getting Started

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

Customers Also Viewed These Support Documents