User Access Verification Password: Password: RLD-VG204-1>en Password: RLD-VG204-1#debug voip vtsp all voip vtsp all debugging is on RLD-VG204-1#debug voip ccapi inout voip ccapi inout debugging is on RLD-VG204-1#debug voip rtp session nse VOIP RTP NSE Events debugging is on RLD-VG204-1#debug vpm signal Voice Port Module signaling debugging is enabled RLD-VG204-1#debug vpm all All Voice Port Module debugging is enabled Note: DSP message debugging is not part of this command. Use debug vpm dsp to enable it RLD-VG204-1#debug mgcp packet Media Gateway Control Protocol packets debugging for all endpoints is on RLD-VG204-1# RLD-VG204-1# RLD-VG204-1# RLD-VG204-1#conf t Enter configuration commands, one per line. End with CNTL/Z. RLD-VG204-1(config)#logging buffered 30000000 RLD-VG204-1(config)#exit RLD-VG204-1# RLD-VG204-1# RLD-VG204-1# RLD-VG204-1#clear log Clear logging buffer [confirm] RLD-VG204-1# RLD-VG204-1# RLD-VG204-1#sh logging Syslog logging: enabled (0 messages dropped, 26 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled) No Active Message Discriminator. No Inactive Message Discriminator. Console logging: level debugging, 5026 messages logged, xml disabled, filtering disabled Monitor logging: level debugging, 0 messages logged, xml disabled, filtering disabled Buffer logging: level debugging, 1492 messages logged, xml disabled, filtering disabled Exception Logging: size (4096 bytes) Count and timestamp logging messages: disabled Persistent logging: disabled Trap logging: level informational, 81 message lines logged Logging Source-Interface: VRF Name: Log Buffer (30000000 bytes): *Apr 13 13:13:48.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531877 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:13:48.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531877 <--- *Apr 13 13:14:03.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531878 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:14:03.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531878 <--- *Apr 13 13:14:18.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531879 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:14:18.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531879 <--- *Apr 13 13:14:33.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531880 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:14:33.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531880 <--- *Apr 13 13:14:48.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531881 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:14:48.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531881 <--- *Apr 13 13:14:53.448: MGCP Packet received from 10.2.55.11:2427---> CRCX 8076373 AALN/S0/0@RLD-VG204-1 MGCP 0.1 C: A000000001a76f2b000000F5 X: 3f7 L: p:20, a:G.729, s:off, t:b8 M: recvonly R: L/hd S: L/rg, L/ci(05/21/13/02,5058889452,) Q: process,loop <--- *Apr 13 13:14:53.448: htsp_validate_cap *Apr 13 13:14:53.448: flex_modem_relay_supported:Modem Relay supported. flex_type=1htsp_allocate_if -- *Apr 13 13:14:53.448: HTSP endpoint_info=aaln/S0/0, type=2, under_specified=0, service_type=2htsp_allocate_if: MATCH! *Apr 13 13:14:53.448: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x878EE5E0, Interface Type=6, Destination=, Mode=0x9, Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=0, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=A000000001a76f2b000000F5) *Apr 13 13:14:53.448: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 13 13:14:53.448: :cc_get_feature_vsa malloc success *Apr 13 13:14:53.448: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 13 13:14:53.448: cc_get_feature_vsa count is 1 *Apr 13 13:14:53.448: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 13 13:14:53.448: :FEATURE_VSA attributes are: feature_name:0,feature_time:2284699896,feature_id:985 *Apr 13 13:14:53.452: //-1/xxxxxxxxxxxx/VTSP:(0/0):-1:-1:-1/vtsp_allocate_cdb: CDB=0x882EBCB0 *Apr 13 13:14:53.452: //-1/xxxxxxxxxxxx/VTSP:(0/0):-1:-1:-1/vtsp_insert_cdb: *Apr 13 13:14:53.452: //985/46E2C5A981B4/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 *Apr 13 13:14:53.452: //985/46E2C5A981B4/CCAPI/ccCallSetContext: Context=0x86AD21D0 *Apr 13 13:14:53.452: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x875C207C, Interface Type=9, Destination=0.0.0.0, Mode=0x9, Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=0, Call Count On=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=A000000001a76f2b000000F5) *Apr 13 13:14:53.452: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 13 13:14:53.452: :cc_get_feature_vsa malloc success *Apr 13 13:14:53.452: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 13 13:14:53.452: cc_get_feature_vsa count is 2 *Apr 13 13:14:53.452: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: *Apr 13 13:14:53.452: :FEATURE_VSA attributes are: feature_name:0,feature_time:2284699672,feature_id:986 *Apr 13 13:14:53.452: //986/46E2C5A981B4/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=9, FlowMode=1 *Apr 13 13:14:53.452: //986/46E2C5A981B4/CCAPI/ccCallSetContext: Context=0x86AD1EF0 *Apr 13 13:14:53.452: //-1/xxxxxxxxxxxx/VTSP:(0/0):-1:-1:-1/vtsp_process_event: [state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ] *Apr 13 13:14:53.456: htsp_timer_stop3 *Apr 13 13:14:53.456: //985/46E2C5A981B4/VTSP:(0/0):-1:-1:-1/vtsp_og_call_req: Echo Cancel=TRUE, Gain Control=0 Auto Gain Control=FALSE, Dual Tone Detect Mask=0x0, Seq Tone Detect=FALSE *Apr 13 13:14:53.456: //985/46E2C5A981B4/VTSP:(0/0):-1:-1:-1/vtsp_do_call_setup_req: *Apr 13 13:14:53.456: //986/46E2C5A981B4/CCAPI/cc_api_call_connected: Interface=0x875C207C, Data Bitmask=0x0, Progress Indication=NULL(0), Connection Handle=0 *Apr 13 13:14:53.456: //986/46E2C5A981B4/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) *Apr 13 13:14:53.460: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_opened_cb: *Apr 13 13:14:53.460: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] *Apr 13 13:14:53.460: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_setup_req_pend_succ: *Apr 13 13:14:53.460: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_request_call: Mode=9, Peer=0 *Apr 13 13:14:53.460: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_create_call_active_on_setup_req: Target Carrier ID= *Apr 13 13:14:53.460: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_create_call_active_on_setup_req: Source Carrier ID= *Apr 13 13:14:53.460: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_call_set_gw_outpulsed_numbers: Unable to update dial_string = htsp_setup_req *Apr 13 13:14:53.464: Orig called num: *Apr 13 13:14:53.464: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_request_call: Progress Indication=0, Info Trans Capability=0 Calling Number=, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed Called Number=, TON=Unknown, NPI=Unknown *Apr 13 13:14:53.464: htsp_process_event: [0/0, FXSLS_ONHOOK, E_HTSP_SETUP_REQ]fxsls_onhook_setup *Apr 13 13:14:53.464: TGRM: reg_invoke_tgrm_call_update(0, -1, 0, 65535, 1, TGRM_CALL_BUSY, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT) *Apr 13 13:14:53.464: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING] *Apr 13 13:14:53.464: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_setup_pend_proceeding: *Apr 13 13:14:53.464: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_timer: Timer Start Time=352616086, Timer Value=25000(ms) *Apr 13 13:14:53.464: //985/46E2C5A981B4/CCAPI/cc_api_call_proceeding: Interface=0x878EE5E0, Progress Indication=NULL(0) *Apr 13 13:14:53.464: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_setup_pend_proceeding: Peer=0, Progress Indication=0, Return Code=2 *Apr 13 13:14:53.464: //985/46E2C5A981B4/CCAPI/ccCallModify: Nominator=0x1000, Params=0x880DAF90, Call Id=985 *Apr 13 13:14:53.464: //985/46E2C5A981B4/CCAPI/ccCallReportDigits: (callID=0x3D9, digit_event=0x1, enable=TRUE, consume=FALSE) *Apr 13 13:14:53.468: //985/46E2C5A981B4/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=985 *Apr 13 13:14:53.468: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_report_digit_control: *Apr 13 13:14:53.468: //985/46E2C5A981B4/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x878EE5E0, callID=0x3D9, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) *Apr 13 13:14:53.468: //985/46E2C5A981B4/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x878EE5E0, Call Id=985 *Apr 13 13:14:53.468: //985/46E2C5A981B4/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) *Apr 13 13:14:53.468: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_report_digit_control: Digit Reporting=TRUE *Apr 13 13:14:53.468: //985/46E2C5A981B4/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFF, callID1=0x3D9, gcid=0-0-0-0, tag=0x0) *Apr 13 13:14:53.468: //986/46E2C5A981B4/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFF, callID2=0x3DA, gcid=0-0-0-0, tag=0x0) *Apr 13 13:14:53.468: //985/46E2C5A981B4/CCAPI/ccConferenceCreate: Conference Id=0xFFFFFFFF, Call Id1=985, Call Id2=986, Tag=0x0 *Apr 13 13:14:53.468: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_bridge_check_cb: *Apr 13 13:14:53.468: htsp_call_bridged invoked *Apr 13 13:14:53.468: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_bridge_check_cb:exit@1496 *Apr 13 13:14:53.468: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_bridge_status_cb: *Apr 13 13:14:53.468: //985/46E2C5A981B4/CCAPI/cc_api_bridge_done: Conference Id=0x1E5, Source Interface=0x878EE5E0, Source Call Id=985, Destination Call Id=986, Disposition=0x0, Tag=0xFFFFFFFF *Apr 13 13:14:53.468: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_save_fax_config: *Apr 13 13:14:53.468: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_set_fax_feat_param: *Apr 13 13:14:53.468: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_save_fax_config: Fax Disabled By=Voice-Port, Primary Fax Protocol=NONE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY *Apr 13 13:14:53.468: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_save_tty_config: *Apr 13 13:14:53.472: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_save_tty_config: Save TTY configuration *Apr 13 13:14:53.472: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_set_tty_feat_param: *Apr 13 13:14:53.472: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_set_tty_feat_param: Set TTY feat parameters *Apr 13 13:14:53.472: //986/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: *Apr 13 13:14:53.472: cc_api_get_xcode_stream : 4987 *Apr 13 13:14:53.472: //986/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: *Apr 13 13:14:53.472: call_info mainst_callID:0x3DA, peer_callID:0x3D9, confID:0x1E5, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 *Apr 13 13:14:53.472: //986/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: *Apr 13 13:14:53.472: stream[0]: callid:0x3DA, media_passthrough:0 *Apr 13 13:14:53.472: //986/46E2C5A981B4/CCAPI/cc_api_bridge_done: Conference Id=0x1E5, Source Interface=0x875C207C, Source Call Id=986, Destination Call Id=985, Disposition=0x0, Tag=0x0 *Apr 13 13:14:53.472: //985/46E2C5A981B4/CCAPI/cc_generic_bridge_done: Conference Id=0x1E5, Source Interface=0x875C207C, Source Call Id=986, Destination Call Id=985, Disposition=0x0, Tag=0x0 *Apr 13 13:14:53.472: //985/46E2C5A981B4/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x1E5, Destination Call Id=986) *Apr 13 13:14:53.472: //986/46E2C5A981B4/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x1E5, Destination Call Id=985) *Apr 13 13:14:53.472: //985/46E2C5A981B4/CCAPI/ccConferenceCreate: *Apr 13 13:14:53.472: confID:0x1E5; callEntry1 callID1:0x3D9, type:6; callEntry2 callID2:0x3DA, type:9 *Apr 13 13:14:53.472: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_NULL, event:E_CC_DO_CAPS_IND] *Apr 13 13:14:53.472: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_IND *Apr 13 13:14:53.472: //985/46E2C5A981B4/CCAPI/cc_api_caps_ind: *Apr 13 13:14:53.472: voice_rtp_nse_payload 0, voice_rtp_sse_payload 118 voice_sprt_payload 120 *Apr 13 13:14:53.472: //985/46E2C5A981B4/CCAPI/cc_api_caps_ind: *Apr 13 13:14:53.472: sprt_latency 200, sprt_retries 12 *Apr 13 13:14:53.472: //985/46E2C5A981B4/CCAPI/cc_api_caps_ind: Destination Interface=0x875C207C, Destination Call Id=986, Source Call Id=985, Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=20, Signal Type=3) *Apr 13 13:14:53.472: //985/46E2C5A981B4/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 13 13:14:53.472: //986/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: *Apr 13 13:14:53.476: cc_api_get_xcode_stream : 4987 *Apr 13 13:14:53.476: //986/46E2C5A981B4/CCAPI/cc_api_caps_ind: *Apr 13 13:14:53.476: voice_rtp_nse_payload 100, voice_rtp_sse_payload 118 voice_sprt_payload 120 *Apr 13 13:14:53.476: //986/46E2C5A981B4/CCAPI/cc_api_caps_ind: *Apr 13 13:14:53.476: sprt_latency 200, sprt_retries 12 *Apr 13 13:14:53.476: //986/46E2C5A981B4/CCAPI/cc_api_caps_ind: Destination Interface=0x878EE5E0, Destination Call Id=985, Source Call Id=986, Caps(Codec=0x4, Fax Rate=0x1, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=20, Signal Type=2) *Apr 13 13:14:53.476: //986/46E2C5A981B4/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 13 13:14:53.476: //986/46E2C5A981B4/CCAPI/cc_api_caps_ack: Destination Interface=0x878EE5E0, Destination Call Id=985, Source Call Id=986, Caps(Codec=g729r8(0x4), Fax Rate=FAX_RATE_NONE(0x1), Fax Version:=0, Vad=OFF(0x1), Modem=ON(0x2), Codec Bytes=20, Signal Type=2, Seq Num Start=5414) *Apr 13 13:14:53.476: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_NULL, event:E_CC_CAPS_IND] *Apr 13 13:14:53.476: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_setup_req_pend_defer: Event=67 *Apr 13 13:14:53.476: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_opened_cb: *Apr 13 13:14:53.480: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec *Apr 13 13:14:53.480: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_get_plc_by_dsp_pltf_intf: vtsp_get_plc_by_dsp_pltf_intf: callID 985, dialpeer tag 1000, plc 1 *Apr 13 13:14:53.480: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_call_mod_done_cb: *Apr 13 13:14:53.480: //985/46E2C5A981B4/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x878EE5E0, Call Id=985 *Apr 13 13:14:53.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_save_fax_config: *Apr 13 13:14:53.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_set_fax_feat_param: *Apr 13 13:14:53.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_save_fax_config: Fax Disabled By=Voice-Port, Primary Fax Protocol=NONE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY *Apr 13 13:14:53.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS] *Apr 13 13:14:53.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_setup_req_wait_pend_success: Event=76 *Apr 13 13:14:53.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_timer_stop: Timer Stop Time=352616088 *Apr 13 13:14:53.484: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec *Apr 13 13:14:53.484: flex_dsprm_forking_mixing_support: *Apr 13 13:14:53.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_ACK *Apr 13 13:14:53.484: //985/46E2C5A981B4/CCAPI/cc_api_caps_ack: Destination Interface=0x875C207C, Destination Call Id=986, Source Call Id=985, Caps(Codec=g729r8(0x4), Fax Rate=FAX_RATE_NONE(0x1), Fax Version:=0, Vad=OFF(0x1), Modem=ON(0x2), Codec Bytes=20, Signal Type=2, Seq Num Start=5414) *Apr 13 13:14:53.484: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec *Apr 13 13:14:53.484: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec *Apr 13 13:14:53.484: htsp_process_event: [0/0, FXSLS_WAIT_OFFHOOK, E_HTSP_VOICE_CUT_THROUGH]fxsls_waitoff_voice *Apr 13 13:14:53.488: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec *Apr 13 13:14:53.488: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_get_plc_by_dsp_pltf_intf: vtsp_get_plc_by_dsp_pltf_intf: callID 985, dialpeer tag 1000, plc 1 *Apr 13 13:14:53.488: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_feature_notify_cb: Feature ID=0, Feature Status=1 *Apr 13 13:14:53.488: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_reactivate_ringback: *Apr 13 13:14:53.488: //985/46E2C5A981B4/CCAPI/cc_api_voice_mode_event: Call Id=985 *Apr 13 13:14:53.488: //985/46E2C5A981B4/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x86AD21D0) *Apr 13 13:14:53.488: htsp_process_event: [0/0, FXSLS_WAIT_OFFHOOK, E_HTSP_VOICE_CUT_THROUGH]fxsls_waitoff_voice *Apr 13 13:14:53.492: //985/46E2C5A981B4/CCAPI/cc_process_notify_bridge_done: Conference Id=0x1E5, Call Id1=985, Call Id2=986 *Apr 13 13:14:53.492: MGCP Packet sent to 10.2.55.11:2427---> 200 8076373 OK I: 1E6 v=0 c=IN IP4 10.55.20.225 m=audio 17352 RTP/AVP 18 100 a=rtpmap:18 G.729/8000 a=fmtp:18 annexb=no a=rtpmap:100 X-NSE/8000 a=fmtp:100 192-194 <--- *Apr 13 13:14:53.492: //985/46E2C5A981B4/CCAPI/ccCallFeature: Feature Type=24, Call Id=985 *Apr 13 13:14:53.492: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_SETUP_REQ_PROC, event:E_CC_FEATURE] *Apr 13 13:14:53.492: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_call_feature: Feature Type=24, Ring Cycle=0htsp_call_feature:feature 24 *Apr 13 13:14:53.492: [0/0] get_caller_id_ring_cadence mode 19, on_time1=2000 off_time1=4000on_time2=0 off_time2=0 *Apr 13 13:14:53.492: [0/0] htsp_set_caller_id_tx_time Caller ID:BELLCORE alerting DURING_RING *Apr 13 13:14:53.492: htsp_process_event: [0/0, FXSLS_WAIT_OFFHOOK, E_HTSP_CALLERID_RINGING]fxsls_onhook_callerid *Apr 13 13:14:53.496: dsp_ring_gen: [0/0] packet_len=26 channel_id=128 packet_id=32 ring1_cadence_on=2000 ring1_cadence_off=4000 ring2_cadence_on=0 ring2_cadence_off=0ring3_cadence_on=0 ring3_cadence_off=0 *Apr 13 13:14:53.496: [0/0] set signal state = 0x0 timestamp = 0 *Apr 13 13:14:53.496: dsp_set_sig_state: [0/0] packet_len=12 channel_id=128 packet_id=39 state=0x0 timestamp=0x0 *Apr 13 13:14:53.496: //986/46E2C5A981B4/CCAPI/cc_api_event_indication: Event=91, Call Id=986 *Apr 13 13:14:53.496: //986/46E2C5A981B4/CCAPI/cc_api_event_indication: Event Is Sent To Conferenced SPI(s) Directly *Apr 13 13:14:53.496: //986/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: *Apr 13 13:14:53.496: cc_api_get_xcode_stream : 4987htsp_alert *Apr 13 13:14:53.496: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_SETUP_REQ_PROC, event:E_TSP_ALERT] *Apr 13 13:14:53.496: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_setup_pend_alert_no_timeout: Progress Indication=8 *Apr 13 13:14:53.496: //985/46E2C5A981B4/CCAPI/cc_api_call_alert: Interface=0x878EE5E0, Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1) *Apr 13 13:14:53.496: //985/46E2C5A981B4/CCAPI/cc_api_call_alert: Call Entry(Retry Count=0, Responsed=TRUE) *Apr 13 13:14:53.496: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_setup_pend_alert_no_timeout: Ringback Indication=TRUE *Apr 13 13:14:56.932: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_feature_notify_cb: Feature ID=6, Feature Status=2 *Apr 13 13:14:56.932: htsp_timer_stop3 *Apr 13 13:14:56.932: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec *Apr 13 13:14:56.932: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_get_plc_by_dsp_pltf_intf: vtsp_get_plc_by_dsp_pltf_intf: callID 985, dialpeer tag 1000, plc 1 *Apr 13 13:14:56.936: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec *Apr 13 13:14:56.936: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec *Apr 13 13:14:56.936: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec *Apr 13 13:14:56.936: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_get_plc_by_dsp_pltf_intf: vtsp_get_plc_by_dsp_pltf_intf: callID 985, dialpeer tag 1000, plc 1 *Apr 13 13:14:56.936: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_feature_notify_cb: Feature ID=0, Feature Status=1 *Apr 13 13:14:56.936: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_reactivate_ringback: *Apr 13 13:14:56.940: //985/46E2C5A981B4/CCAPI/cc_api_voice_mode_event: Call Id=985 *Apr 13 13:14:56.940: //985/46E2C5A981B4/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x86AD21D0) *Apr 13 13:14:56.940: htsp_process_event: [0/0, FXSLS_WAIT_OFFHOOK, E_HTSP_VOICE_CUT_THROUGH]fxsls_waitoff_voice *Apr 13 13:15:03.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531882 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:15:03.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531882 <--- *Apr 13 13:15:08.468: //985/46E2C5A981B4/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event *Apr 13 13:15:08.468: //985/46E2C5A981B4/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event *Apr 13 13:15:18.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531883 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:15:18.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531883 <--- *Apr 13 13:15:33.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531884 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:15:33.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531884 <--- *Apr 13 13:15:48.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531885 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:15:48.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531885 <--- *Apr 13 13:15:54.476: MGCP Packet received from 10.2.55.11:2427---> DLCX 8076456 AALN/S0/0@RLD-VG204-1 MGCP 0.1 C: A000000001a76f2b000000F5 I: 1E6 X: 3f8 R: L/hd S: Q: process,loop <--- *Apr 13 13:15:54.476: //985/46E2C5A981B4/CCAPI/ccCallFeature: Feature Type=34, Call Id=985 *Apr 13 13:15:54.476: //985/46E2C5A981B4/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Null, Tone Direction=Network, Params=0x0, Call Id=985 *Apr 13 13:15:54.476: //985/46E2C5A981B4/CCAPI/ccCallModify: Nominator=0x1000, Params=0x880DAF14, Call Id=985 *Apr 13 13:15:54.480: //986/46E2C5A981B4/CCAPI/ccGetCallStatistics: Call Stats=0x882774E8, Call Id=986 *Apr 13 13:15:54.480: //985/46E2C5A981B4/CCAPI/ccConferenceDestroy: Conference Id=0x1E5, Tag=0x0 *Apr 13 13:15:54.480: //985/46E2C5A981B4/CCAPI/ccConferenceDestroy: *Apr 13 13:15:54.480: confID:0x1E5; callEntry1 callID1:0x3D9, type:6; callEntry2 callID2:0x3DA, type:9 *Apr 13 13:15:54.480: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_bridge_status_cb: *Apr 13 13:15:54.480: //985/46E2C5A981B4/CCAPI/cc_api_bridge_drop_done: Conference Id=0x1E5, Source Interface=0x878EE5E0, Source Call Id=985, Destination Call Id=986, Disposition=0x0, Tag=0x0 *Apr 13 13:15:54.480: //986/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: *Apr 13 13:15:54.480: cc_api_get_xcode_stream : 4987 *Apr 13 13:15:54.480: //986/46E2C5A981B4/CCAPI/cc_api_bridge_drop_done: Conference Id=0x1E5, Source Interface=0x875C207C, Source Call Id=986, Destination Call Id=985, Disposition=0x0, Tag=0x0 *Apr 13 13:15:54.480: //985/46E2C5A981B4/CCAPI/cc_generic_bridge_done: Conference Id=0x1E5, Source Interface=0x875C207C, Source Call Id=986, Destination Call Id=985, Disposition=0x0, Tag=0x0 *Apr 13 13:15:54.480: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_SETUP_REQ_PROC, event:E_CC_FEATURE] *Apr 13 13:15:54.480: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_call_feature: Feature Type=34htsp_call_feature:feature 34 *Apr 13 13:15:54.480: //985/46E2C5A981B4/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) *Apr 13 13:15:54.480: //985/46E2C5A981B4/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) *Apr 13 13:15:54.480: //985/46E2C5A981B4/CCAPI/cc_api_get_transfer_info: Transfer Number=NULL *Apr 13 13:15:54.484: //986/46E2C5A981B4/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) *Apr 13 13:15:54.484: //986/46E2C5A981B4/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) *Apr 13 13:15:54.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_SETUP_REQ_PROC, event:E_CC_DISCONNECT] *Apr 13 13:15:54.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_disconnect: Cause Value=16 *Apr 13 13:15:54.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_ring_noan_timer_stop: Timer Stop Time=352622188 *Apr 13 13:15:54.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_pcm_cap_conf_tone_timer_stop: Timer Stop Time=352622188 *Apr 13 13:15:54.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_save_fax_config: *Apr 13 13:15:54.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_set_fax_feat_param: *Apr 13 13:15:54.484: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_save_fax_config: Fax Disabled By=Voice-Port, Primary Fax Protocol=NONE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY *Apr 13 13:15:54.484: htsp_timer_stop3 *Apr 13 13:15:54.484: htsp_process_event: [0/0, FXSLS_WAIT_OFFHOOK, E_HTSP_STOP_RINGING]fxsls_stop_ringing *Apr 13 13:15:54.484: [0/0] set signal state = 0x4 timestamp = 0 *Apr 13 13:15:54.484: dsp_set_sig_state: [0/0] packet_len=12 channel_id=128 packet_id=39 state=0x4 timestamp=0x0 *Apr 13 13:15:54.488: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_call_mod_done_cb: *Apr 13 13:15:54.488: //985/46E2C5A981B4/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x878EE5E0, Call Id=985 *Apr 13 13:15:54.488: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_save_fax_config: *Apr 13 13:15:54.488: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_set_fax_feat_param: *Apr 13 13:15:54.488: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_save_fax_config: Fax Disabled By=Voice-Port, Primary Fax Protocol=NONE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY *Apr 13 13:15:54.496: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_dsm_get_levels_done_cb: *Apr 13 13:15:54.500: //986/46E2C5A981B4/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x875C207C, Tag=0x0, Call Id=986, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) *Apr 13 13:15:54.500: //986/46E2C5A981B4/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Apr 13 13:15:54.500: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Apr 13 13:15:54.500: :cc_free_feature_vsa freeing 882DC010 *Apr 13 13:15:54.500: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Apr 13 13:15:54.504: vsacount in free is 1 *Apr 13 13:15:54.508: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE] *Apr 13 13:15:54.508: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_dsm_dsp_stats_complete: htsp_release_req: cause 16, no_onhook 1 *Apr 13 13:15:54.508: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_timer: Timer Start Time=352622190, Timer Value=60000(ms) *Apr 13 13:15:54.508: htsp_process_event: [0/0, FXSLS_WAIT_OFFHOOK, E_HTSP_RELEASE_REQ]fxsls_waitoff_release *Apr 13 13:15:54.508: [0/0] set signal state = 0x4 timestamp = 0 *Apr 13 13:15:54.508: dsp_set_sig_state: [0/0] packet_len=12 channel_id=128 packet_id=39 state=0x4 timestamp=0x0 *Apr 13 13:15:54.508: TGRM: reg_invoke_tgrm_call_update(0, -1, 0, 65535, 1, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT) *Apr 13 13:15:54.512: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_process_event: [state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF] *Apr 13 13:15:54.512: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/act_wrelease_release: *Apr 13 13:15:54.512: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_play_busy_timer_stop: Timer Stop Time=352622190 *Apr 13 13:15:54.512: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_timer_stop: Timer Stop Time=352622190 *Apr 13 13:15:54.512: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_do_call_history: *Apr 13 13:15:54.512: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_do_call_history: Coder Rate=16 *Apr 13 13:15:54.512: //985/46E2C5A981B4/VTSP:(0/0):-1:1:1/vtsp_is_valid_dsm_handle: DMGR=0x85C79874, VTSP CDB=0x882EBCB0 *Apr 13 13:15:54.516: flex_dsprm_close_cleanup *Apr 13 13:15:54.516: //985/46E2C5A981B4/VTSP:(0/0):-1:-1:-1/vtsp_dsm_closed_cb: *Apr 13 13:15:54.516: //985/46E2C5A981B4/VTSP:(0/0):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] *Apr 13 13:15:54.516: //985/46E2C5A981B4/VTSP:(0/0):-1:-1:-1/act_terminate: *Apr 13 13:15:54.516: //985/46E2C5A981B4/VTSP:(0/0):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=352622191 *Apr 13 13:15:54.516: //985/46E2C5A981B4/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x878EE5E0, Tag=0x0, Call Id=985, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) *Apr 13 13:15:54.516: //985/46E2C5A981B4/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent *Apr 13 13:15:54.516: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Apr 13 13:15:54.516: :cc_free_feature_vsa freeing 882DC0F0 *Apr 13 13:15:54.516: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: *Apr 13 13:15:54.516: vsacount in free is 0 *Apr 13 13:15:54.520: //-1/46E2C5A981B4/VTSP:(0/0):-1:-1:-1/vtsp_free_cdb: CDB=0x882EBCB0 *Apr 13 13:15:54.520: MGCP Packet sent to 10.2.55.11:2427---> 250 8076456 OK P: PS=0, OS=0, PR=0, OR=0, PL=0, JI=1, LA=0 <--- *Apr 13 13:16:03.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531886 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:16:03.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531886 <--- *Apr 13 13:16:18.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531887 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:16:18.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531887 <--- *Apr 13 13:16:33.532: MGCP Packet sent to 10.2.55.11:2427---> NTFY 525531888 *@RLD-VG204-1 MGCP 0.1 X: 0 O: <--- *Apr 13 13:16:33.536: MGCP Packet received from 10.2.55.11:2427---> 200 525531888 <--- RLD-VG204-1