login as: achaudhary ========================sfsivrt1=============================== Using keyboard-interactive authentication. Password: *************************************************************** WARNING: To protect the system from fraud and abuse, activities on this system are monitored and subject to audit. Use of this system is expressed consent to monitor. Unauthorized access to this classified device is prohibited *************************************************************** ############################################################### WARNING: To protect the system from fraud and abuse, activities on this system are monitored and subject to audit. Use of this system is expressed consent to monitor. Unauthorized access to this classified device is prohibited. ############################################################### sfsivrt01>en Password: sfsivrt01#debug voip vtsp all voip vtsp all debugging is on sfsivrt01#debug voip ccapi inout voip ccapi inout debugging is on sfsivrt01#debug voip rtp session nse VOIP RTP NSE Events debugging is on sfsivrt01#debug vpm signal Voice Port Module signaling debugging is enabled sfsivrt01#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 sfsivrt01#debug mgcp packet Media Gateway Control Protocol packets debugging for all endpoints is on sfsivrt01# sfsivrt01# sfsivrt01# sfsivrt01#conf t Enter configuration commands, one per line. End with CNTL/Z. sfsivrt01(config)#logging buffered 3000000 sfsivrt01(config)#exit sfsivrt01# sfsivrt01# sfsivrt01# sfsivrt01# sfsivrt01# sfsivrt01#clear log Clear logging buffer [confirm] sfsivrt01#sh logging Syslog logging: enabled (0 messages dropped, 35 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled) No Active Message Discriminator. No Inactive Message Discriminator. Console logging: disabled Monitor logging: disabled Buffer logging: level debugging, 24536 messages logged, xml disabled, filtering disabled Exception Logging: size (4096 bytes) Count and timestamp logging messages: disabled Persistent logging: disabled No active filter modules. Trap logging: level alerts, 0 message lines logged Logging to 10.2.34.71 (udp port 514, audit disabled, link down), 0 message lines logged, 0 message lines rate-limited, 0 message lines dropped-by-MD, xml disabled, sequence number disabled filtering disabled Logging Source-Interface: VRF Name: Loopback0 Log Buffer (3000000 bytes): 052536: May 21 13:01:51.218: MGCP Packet sent to 10.2.55.11:2427---> NTFY 304023860 *@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 0 O: <--- 052537: May 21 13:01:51.219: MGCP Packet received from 10.2.55.11:2427---> 200 304023860 <--- 052538: May 21 13:01:51.838: MGCP Packet received from 10.2.55.11:2427---> CRCX 8076288 S0/SU1/DS1-0/1@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a76eac000000F500006eb7 X: 1 L: p:20, a:PCMU;telephone-event, fmtp:"telephone-event 0-15", s:off, t:b8 M: recvonly R: D/[0-9ABCD*#] Q: process,loop <--- 052539: May 21 13:01:51.839: flex_modem_relay_supported:Modem Relay supported. flex_type=1htsp_allocate_if -- 052540: May 21 13:01:51.839: HTSP endpoint_info=S0/SU1/DS1-0/1, type=0, under_specified=0, service_type=2 052541: May 21 13:01:51.839: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x7F879CBFA3D0, 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=D000000001a76eac000000F500006eb7) 052542: May 21 13:01:51.839: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052543: May 21 13:01:51.839: :cc_get_feature_vsa malloc success 052544: May 21 13:01:51.839: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052545: May 21 13:01:51.839: cc_get_feature_vsa count is 9 052546: May 21 13:01:51.839: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052547: May 21 13:01:51.839: :FEATURE_VSA attributes are: feature_name:0,feature_time:140220370302540,feature_id:152231 052548: May 21 13:01:51.840: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_allocate_cdb: CDB=0x7F879F5A2918 052549: May 21 13:01:51.840: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_insert_cdb: 052550: May 21 13:01:51.840: //152231/BA293CD2B03C/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 052551: May 21 13:01:51.840: //152231/BA293CD2B03C/CCAPI/ccCallSetContext: Context=0x7F879EB67E58 052552: May 21 13:01:51.840: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x7F8793AD3208, 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=D000000001a76eac000000F500006eb7) 052553: May 21 13:01:51.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052554: May 21 13:01:51.840: :cc_get_feature_vsa malloc success 052555: May 21 13:01:51.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052556: May 21 13:01:51.840: cc_get_feature_vsa count is 10 052557: May 21 13:01:51.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052558: May 21 13:01:51.840: :FEATURE_VSA attributes are: feature_name:0,feature_time:140220370304332,feature_id:152232 052559: May 21 13:01:51.840: //152232/BA293CD2B03C/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=9, FlowMode=1 052560: May 21 13:01:51.841: //152232/BA293CD2B03C/CCAPI/ccCallSetContext: Context=0x7F879EB65A68 052561: May 21 13:01:51.841: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_process_event: [state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ] 052562: May 21 13:01:51.841: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-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 052563: May 21 13:01:51.841: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:-1:-1/vtsp_do_call_setup_req: 052564: May 21 13:01:51.841: //152232/BA293CD2B03C/CCAPI/cc_api_call_connected: Interface=0x7F8793AD3208, Data Bitmask=0x0, Progress Indication=NULL(0), Connection Handle=0 052565: May 21 13:01:51.842: //152232/BA293CD2B03C/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) 052566: May 21 13:01:51.842: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_opened_cb: 052567: May 21 13:01:51.842: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_process_event: [state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] 052568: May 21 13:01:51.842: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/act_setup_req_pend_succ: 052569: May 21 13:01:51.842: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_request_call: Mode=9, Peer=0 052570: May 21 13:01:51.843: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_create_call_active_on_setup_req: Target Carrier ID= 052571: May 21 13:01:51.843: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_create_call_active_on_setup_req: Source Carrier ID= 052572: May 21 13:01:51.843: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_call_set_gw_outpulsed_numbers: Unable to update dial_string = 052573: May 21 13:01:51.843: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-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 052574: May 21 13:01:51.843: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_process_event: [state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING] 052575: May 21 13:01:51.843: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/act_setup_pend_proceeding: 052576: May 21 13:01:51.843: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_timer: Timer Start Time=287042455, Timer Value=25000(ms) 052577: May 21 13:01:51.843: //152231/BA293CD2B03C/CCAPI/cc_api_call_proceeding: Interface=0x7F879CBFA3D0, Progress Indication=NULL(0) 052578: May 21 13:01:51.843: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/act_setup_pend_proceeding: Peer=0, Progress Indication=0, Return Code=2 052579: May 21 13:01:51.843: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_process_event: [state:S_NULL, event:E_TSP_CONNECT] 052580: May 21 13:01:51.843: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/act_setup_req_pend_defer: Event=7 052581: May 21 13:01:51.847: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_opened_cb: 052582: May 21 13:01:51.847: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_process_event: [state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS] 052583: May 21 13:01:51.847: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/act_setup_req_wait_pend_success: Event=76 052584: May 21 13:01:51.847: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_timer_stop: Timer Stop Time=287042456 052585: May 21 13:01:51.847: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_ring_noan_timer_stop: Timer Stop Time=287042456 052586: May 21 13:01:51.847: //152231/BA293CD2B03C/CCAPI/cc_api_call_connected: Interface=0x7F879CBFA3D0, Data Bitmask=0x1, Progress Indication=DESTINATION IS NON ISDN(2), Connection Handle=0 052587: May 21 13:01:51.847: //152231/BA293CD2B03C/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) 052588: May 21 13:01:51.847: //152231/BA293CD2B03C/CCAPI/ccCallModify: Nominator=0x1000, Params=0x7F879E160C98, Call Id=152231 052589: May 21 13:01:51.847: //152231/BA293CD2B03C/CCAPI/ccCallReportDigits: (callID=0x252A7, digit_event=0x1, enable=TRUE, consume=FALSE) 052590: May 21 13:01:51.847: //152231/BA293CD2B03C/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152231 052591: May 21 13:01:51.848: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_report_digit_control: 052592: May 21 13:01:51.848: //152231/BA293CD2B03C/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x252A7, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) 052593: May 21 13:01:51.848: //152231/BA293CD2B03C/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152231 052594: May 21 13:01:51.848: //152231/BA293CD2B03C/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) 052595: May 21 13:01:51.848: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_report_digit_control: Digit Reporting=TRUE 052596: May 21 13:01:51.848: //152231/BA293CD2B03C/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFFFFFFFFFF, callID1=0x252A7, gcid=0-0-0-0, tag=0x0) 052597: May 21 13:01:51.848: //152232/BA293CD2B03C/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFFFFFFFFFF, callID2=0x252A8, gcid=0-0-0-0, tag=0x0) 052598: May 21 13:01:51.848: //152231/BA293CD2B03C/CCAPI/ccConferenceCreate: Conference Id=0xFFFFFFFFFFFFFFFF, Call Id1=152231, Call Id2=152232, Tag=0x0 052599: May 21 13:01:51.848: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_bridge_check_cb: 052600: May 21 13:01:51.848: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_bridge_check_cb:exit@1500 052601: May 21 13:01:51.848: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_bridge_status_cb: 052602: May 21 13:01:51.848: //152231/BA293CD2B03C/CCAPI/cc_api_bridge_done: Conference Id=0x12954, Source Interface=0x7F879CBFA3D0, Source Call Id=152231, Destination Call Id=152232, Disposition=0x0, Tag=0xFFFFFFFFFFFFFFFF 052603: May 21 13:01:51.848: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_save_fax_config: 052604: May 21 13:01:51.848: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_save_fax_config: Fax Relay=DISABLED - MGCP Application Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=IGNORE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 052605: May 21 13:01:51.848: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_set_fax_feat_param: 052606: May 21 13:01:51.849: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_save_tty_config: 052607: May 21 13:01:51.849: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_save_tty_config: Save TTY configuration 052608: May 21 13:01:51.849: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_set_tty_feat_param: 052609: May 21 13:01:51.849: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_set_tty_feat_param: Set TTY feat parameters 052610: May 21 13:01:51.849: //152232/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 052611: May 21 13:01:51.849: cc_api_get_xcode_stream : 4981 052612: May 21 13:01:51.849: //152232/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 052613: May 21 13:01:51.849: call_info mainst_callID:0x252A8, peer_callID:0x252A7, confID:0x12954, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 052614: May 21 13:01:51.849: //152232/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 052615: May 21 13:01:51.849: stream[0]: callid:0x252A8, media_passthrough:0 052616: May 21 13:01:51.849: //152232/BA293CD2B03C/CCAPI/cc_api_bridge_done: Conference Id=0x12954, Source Interface=0x7F8793AD3208, Source Call Id=152232, Destination Call Id=152231, Disposition=0x0, Tag=0x0 052617: May 21 13:01:51.849: //152231/BA293CD2B03C/CCAPI/cc_generic_bridge_done: Conference Id=0x12954, Source Interface=0x7F8793AD3208, Source Call Id=152232, Destination Call Id=152231, Disposition=0x0, Tag=0x0 052618: May 21 13:01:51.850: //152231/BA293CD2B03C/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x12954, Destination Call Id=152232) 052619: May 21 13:01:51.850: //152232/BA293CD2B03C/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x12954, Destination Call Id=152231) 052620: May 21 13:01:51.850: //152231/BA293CD2B03C/CCAPI/ccConferenceCreate: 052621: May 21 13:01:51.850: confID:0x12954; callEntry1 callID1:0x252A7, type:6; callEntry2 callID2:0x252A8, type:9 052622: May 21 13:01:51.850: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_process_event: [state:S_CONNECT, event:E_CC_DO_CAPS_IND] 052623: May 21 13:01:51.850: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_IND 052624: May 21 13:01:51.850: //152231/BA293CD2B03C/CCAPI/cc_api_caps_ind: 052625: May 21 13:01:51.850: voice_rtp_nse_payload 0, voice_rtp_sse_payload 118 voice_sprt_payload 120 052626: May 21 13:01:51.850: //152231/BA293CD2B03C/CCAPI/cc_api_caps_ind: 052627: May 21 13:01:51.850: sprt_latency 200, sprt_retries 12 052628: May 21 13:01:51.850: //152231/BA293CD2B03C/CCAPI/cc_api_caps_ind: Destination Interface=0x7F8793AD3208, Destination Call Id=152232, Source Call Id=152231, Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=20, Signal Type=3) 052629: May 21 13:01:51.851: //152231/BA293CD2B03C/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)) 052630: May 21 13:01:51.851: //152232/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 052631: May 21 13:01:51.851: cc_api_get_xcode_stream : 4981 052632: May 21 13:01:51.851: //152232/BA293CD2B03C/CCAPI/cc_api_caps_ind: 052633: May 21 13:01:51.851: voice_rtp_nse_payload 100, voice_rtp_sse_payload 118 voice_sprt_payload 120 052634: May 21 13:01:51.851: //152232/BA293CD2B03C/CCAPI/cc_api_caps_ind: 052635: May 21 13:01:51.851: sprt_latency 200, sprt_retries 12 052636: May 21 13:01:51.851: //152232/BA293CD2B03C/CCAPI/cc_api_caps_ind: Destination Interface=0x7F879CBFA3D0, Destination Call Id=152231, Source Call Id=152232, Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=160, Signal Type=2) 052637: May 21 13:01:51.851: //152232/BA293CD2B03C/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)) 052638: May 21 13:01:51.851: //152232/BA293CD2B03C/CCAPI/cc_api_caps_ack: Destination Interface=0x7F879CBFA3D0, Destination Call Id=152231, Source Call Id=152232, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1), Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=8077) 052639: May 21 13:01:51.851: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_process_event: [state:S_CONNECT, event:E_CC_CAPS_IND] 052640: May 21 13:01:51.852: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052641: May 21 13:01:51.852: flex_dsprm_forking_mixing_support: 052642: May 21 13:01:51.852: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_ACK 052643: May 21 13:01:51.852: //152231/BA293CD2B03C/CCAPI/cc_api_caps_ack: Destination Interface=0x7F8793AD3208, Destination Call Id=152232, Source Call Id=152231, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1), Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=8077) 052644: May 21 13:01:51.853: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052645: May 21 13:01:51.856: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_call_mod_done_cb: 052646: May 21 13:01:51.856: //152231/BA293CD2B03C/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F879CBFA3D0, Call Id=152231 052647: May 21 13:01:51.856: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_save_fax_config: 052648: May 21 13:01:51.856: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_save_fax_config: Fax Relay=DISABLED - MGCP Application Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=IGNORE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 052649: May 21 13:01:51.856: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_set_fax_feat_param: 052650: May 21 13:01:51.857: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052651: May 21 13:01:51.857: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_feature_notify_cb: Feature ID=0, Feature Status=1 052652: May 21 13:01:51.857: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_reactivate_ringback: 052653: May 21 13:01:51.857: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_reactivate_ringback:exit@1291 052654: May 21 13:01:51.857: //152231/BA293CD2B03C/CCAPI/cc_api_voice_mode_event: Call Id=152231 052655: May 21 13:01:51.857: //152231/BA293CD2B03C/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x7F879EB67E58) 052656: May 21 13:01:51.858: //152231/BA293CD2B03C/CCAPI/cc_process_notify_bridge_done: Conference Id=0x12954, Call Id1=152231, Call Id2=152232 052657: May 21 13:01:51.858: MGCP Packet sent to 10.2.55.11:2427---> 200 8076288 OK I: 12955 v=0 c=IN IP4 10.0.15.178 m=audio 40480 RTP/AVP 0 99 100 a=rtpmap:99 telephone-event/8000 a=fmtp:99 0-15 a=rtpmap:100 X-NSE/8000 a=fmtp:100 192-194 <--- 052658: May 21 13:01:51.858: //152231/BA293CD2B03C/CCAPI/ccSetDigitTimeouts: Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms) 052659: May 21 13:01:51.858: //152231/BA293CD2B03C/CCAPI/ccSetDigitTimeouts: Call Entry(Inter Digit Timeout=4000(ms), Initial Digit Timeout=4000(ms)) 052660: May 21 13:01:51.859: //152231/BA293CD2B03C/CCAPI/ccRestartDigitTimeoutMsec: Digit Timeout=0, Call Id=152231 052661: May 21 13:01:51.859: //152231/BA293CD2B03C/CCAPI/ccCallReportDigits: (callID=0x252A7, digit_event=0x1, enable=TRUE, consume=FALSE) 052662: May 21 13:01:51.859: //152231/BA293CD2B03C/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152231 052663: May 21 13:01:51.859: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_report_digit_control: 052664: May 21 13:01:51.859: //152231/BA293CD2B03C/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x252A7, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) 052665: May 21 13:01:51.859: //152231/BA293CD2B03C/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152231 052666: May 21 13:01:51.860: //152231/BA293CD2B03C/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)) 052667: May 21 13:01:51.860: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_report_digit_control: Digit Reporting=TRUE 052668: May 21 13:01:51.860: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_ENABLE_TDM_RTCP 052669: May 21 13:01:51.899: MGCP Packet received from 10.2.55.11:2427---> MDCX 8076289 S0/SU1/DS1-0/1@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a76eac000000F500006eb7 I: 12955 X: 1 L: p:20, a:PCMU;telephone-event, fmtp:"telephone-event 0-15", s:off, t:b8 M: sendrecv R: D/[0-9ABCD*#] S: Q: process,loop v=0 o=- 76117 0 IN EPN S0/SU1/DS1-0/1@sfsivrt01.doit.state.nm.us s=Cisco SDP 0 t=0 0 m=audio 17480 RTP/AVP 0 101 c=IN IP4 10.5.12.22 a=rtpmap:101 telephone-event a=fmtp:101 0-15 <--- 052670: May 21 13:01:51.900: //152231/BA293CD2B03C/CCAPI/ccCallModifyExtended: Nominator=0x7F879E15FC70, Params=0x7F879E160B18, Call Id=152231 052671: May 21 13:01:51.900: //152232/BA293CD2B03C/CCAPI/ccCallModify: Nominator=0x20018E30, Params=0x7F879E160488, Call Id=152232 052672: May 21 13:01:51.900: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052673: May 21 13:01:51.905: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_call_mod_done_cb: 052674: May 21 13:01:51.905: //152231/BA293CD2B03C/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F879CBFA3D0, Call Id=152231 052675: May 21 13:01:51.905: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_save_fax_config: 052676: May 21 13:01:51.905: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_save_fax_config: 052677: May 21 13:01:51.905: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 052678: May 21 13:01:51.905: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_set_fax_feat_param: 052679: May 21 13:01:51.905: //152232/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 052680: May 21 13:01:51.905: cc_api_get_xcode_stream : 4981 052681: May 21 13:01:51.905: //152232/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 052682: May 21 13:01:51.906: cc_api_get_xcode_stream : 4981 052683: May 21 13:01:51.906: //152232/BA293CD2B03C/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F8793AD3208, Call Id=152232 052684: May 21 13:01:51.906: //152232/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 052685: May 21 13:01:51.906: call_info mainst_callID:0x252A8, peer_callID:0x252A7, confID:0x12954, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 052686: May 21 13:01:51.906: //152232/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 052687: May 21 13:01:51.906: stream[0]: callid:0x252A8, media_passthrough:0 052688: May 21 13:01:51.907: MGCP Packet sent to 10.2.55.11:2427---> 200 8076289 OK v=0 c=IN IP4 10.0.15.178 m=audio 40480 RTP/AVP 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <--- 052689: May 21 13:01:51.908: //152231/BA293CD2B03C/VTSP:(0/1/0:23):-1:1:1/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_ENABLE_TDM_RTCP 052690: May 21 13:01:55.860: //152231/BA293CD2B03C/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event 052691: May 21 13:01:55.860: //152231/BA293CD2B03C/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event 052692: May 21 13:02:06.218: MGCP Packet sent to 10.2.55.11:2427---> NTFY 304023861 *@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 0 O: <--- 052693: May 21 13:02:06.219: MGCP Packet received from 10.2.55.11:2427---> 200 304023861 <--- 052694: May 21 13:02:21.218: MGCP Packet sent to 10.2.55.11:2427---> NTFY 304023862 *@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 0 O: <--- 052695: May 21 13:02:21.219: MGCP Packet received from 10.2.55.11:2427---> 200 304023862 <--- 052696: May 21 13:02:36.218: MGCP Packet sent to 10.2.55.11:2427---> NTFY 304023863 *@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 0 O: <--- 052697: May 21 13:02:36.219: MGCP Packet received from 10.2.55.11:2427---> 200 304023863 <--- 052698: May 21 13:02:51.219: MGCP Packet sent to 10.2.55.11:2427---> NTFY 304023864 *@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 0 O: <--- 052699: May 21 13:02:51.220: MGCP Packet received from 10.2.55.11:2427---> 200 304023864 <--- 052700: May 21 13:02:57.604: MGCP Packet received from 10.2.55.11:2427---> CRCX 8076372 S0/SU1/DS1-0/5@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a76f2a000000F580007163 X: 5 L: p:20, a:PCMU;telephone-event, fmtp:"telephone-event 0-15", s:off, t:b8 M: recvonly R: D/[0-9ABCD*#] Q: process,loop <--- 052701: May 21 13:02:57.605: flex_modem_relay_supported:Modem Relay supported. flex_type=1htsp_allocate_if -- 052702: May 21 13:02:57.605: HTSP endpoint_info=S0/SU1/DS1-0/5, type=0, under_specified=0, service_type=2 052703: May 21 13:02:57.605: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x7F879CBFA3D0, 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=D000000001a76f2a000000F580007163) 052704: May 21 13:02:57.605: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052705: May 21 13:02:57.605: :cc_get_feature_vsa malloc success 052706: May 21 13:02:57.605: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052707: May 21 13:02:57.605: cc_get_feature_vsa count is 11 052708: May 21 13:02:57.605: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052709: May 21 13:02:57.605: :FEATURE_VSA attributes are: feature_name:0,feature_time:140220370300972,feature_id:152233 052710: May 21 13:02:57.605: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_allocate_cdb: CDB=0x7F879F59F8D0 052711: May 21 13:02:57.606: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_insert_cdb: 052712: May 21 13:02:57.606: //152233/E15C55ECB03D/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 052713: May 21 13:02:57.606: //152233/E15C55ECB03D/CCAPI/ccCallSetContext: Context=0x7F879EB67B38 052714: May 21 13:02:57.606: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x7F8793AD3208, 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=D000000001a76f2a000000F580007163) 052715: May 21 13:02:57.606: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052716: May 21 13:02:57.606: :cc_get_feature_vsa malloc success 052717: May 21 13:02:57.606: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052718: May 21 13:02:57.606: cc_get_feature_vsa count is 12 052719: May 21 13:02:57.606: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052720: May 21 13:02:57.606: :FEATURE_VSA attributes are: feature_name:0,feature_time:140220370293132,feature_id:152234 052721: May 21 13:02:57.606: //152234/E15C55ECB03D/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=9, FlowMode=1 052722: May 21 13:02:57.606: //152234/E15C55ECB03D/CCAPI/ccCallSetContext: Context=0x7F879EB65040 052723: May 21 13:02:57.607: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_process_event: [state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ] 052724: May 21 13:02:57.607: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-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 052725: May 21 13:02:57.607: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:-1:-1/vtsp_do_call_setup_req: 052726: May 21 13:02:57.607: //152234/E15C55ECB03D/CCAPI/cc_api_call_connected: Interface=0x7F8793AD3208, Data Bitmask=0x0, Progress Indication=NULL(0), Connection Handle=0 052727: May 21 13:02:57.607: //152234/E15C55ECB03D/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) 052728: May 21 13:02:57.608: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_opened_cb: 052729: May 21 13:02:57.608: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_process_event: [state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] 052730: May 21 13:02:57.608: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/act_setup_req_pend_succ: 052731: May 21 13:02:57.608: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_request_call: Mode=9, Peer=0 052732: May 21 13:02:57.608: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_create_call_active_on_setup_req: Target Carrier ID= 052733: May 21 13:02:57.608: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_create_call_active_on_setup_req: Source Carrier ID= 052734: May 21 13:02:57.608: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_call_set_gw_outpulsed_numbers: Unable to update dial_string = 052735: May 21 13:02:57.609: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/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 052736: May 21 13:02:57.609: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_process_event: [state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING] 052737: May 21 13:02:57.609: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/act_setup_pend_proceeding: 052738: May 21 13:02:57.609: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_timer: Timer Start Time=287049032, Timer Value=25000(ms) 052739: May 21 13:02:57.609: //152233/E15C55ECB03D/CCAPI/cc_api_call_proceeding: Interface=0x7F879CBFA3D0, Progress Indication=NULL(0) 052740: May 21 13:02:57.609: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/act_setup_pend_proceeding: Peer=0, Progress Indication=0, Return Code=2 052741: May 21 13:02:57.609: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_process_event: [state:S_NULL, event:E_TSP_CONNECT] 052742: May 21 13:02:57.609: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/act_setup_req_pend_defer: Event=7 052743: May 21 13:02:57.613: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_opened_cb: 052744: May 21 13:02:57.613: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_process_event: [state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS] 052745: May 21 13:02:57.613: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/act_setup_req_wait_pend_success: Event=76 052746: May 21 13:02:57.613: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_timer_stop: Timer Stop Time=287049032 052747: May 21 13:02:57.613: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_ring_noan_timer_stop: Timer Stop Time=287049032 052748: May 21 13:02:57.614: //152233/E15C55ECB03D/CCAPI/cc_api_call_connected: Interface=0x7F879CBFA3D0, Data Bitmask=0x1, Progress Indication=DESTINATION IS NON ISDN(2), Connection Handle=0 052749: May 21 13:02:57.614: //152233/E15C55ECB03D/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) 052750: May 21 13:02:57.614: //152233/E15C55ECB03D/CCAPI/ccCallModify: Nominator=0x1000, Params=0x7F879E160C98, Call Id=152233 052751: May 21 13:02:57.614: //152233/E15C55ECB03D/CCAPI/ccCallReportDigits: (callID=0x252A9, digit_event=0x1, enable=TRUE, consume=FALSE) 052752: May 21 13:02:57.614: //152233/E15C55ECB03D/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152233 052753: May 21 13:02:57.614: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_report_digit_control: 052754: May 21 13:02:57.614: //152233/E15C55ECB03D/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x252A9, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) 052755: May 21 13:02:57.615: //152233/E15C55ECB03D/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152233 052756: May 21 13:02:57.615: //152233/E15C55ECB03D/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) 052757: May 21 13:02:57.615: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_report_digit_control: Digit Reporting=TRUE 052758: May 21 13:02:57.615: //152233/E15C55ECB03D/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFFFFFFFFFF, callID1=0x252A9, gcid=0-0-0-0, tag=0x0) 052759: May 21 13:02:57.615: //152234/E15C55ECB03D/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFFFFFFFFFF, callID2=0x252AA, gcid=0-0-0-0, tag=0x0) 052760: May 21 13:02:57.615: //152233/E15C55ECB03D/CCAPI/ccConferenceCreate: Conference Id=0xFFFFFFFFFFFFFFFF, Call Id1=152233, Call Id2=152234, Tag=0x0 052761: May 21 13:02:57.615: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_bridge_check_cb: 052762: May 21 13:02:57.615: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_bridge_check_cb:exit@1500 052763: May 21 13:02:57.615: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_bridge_status_cb: 052764: May 21 13:02:57.617: //152233/E15C55ECB03D/CCAPI/cc_api_bridge_done: Conference Id=0x12955, Source Interface=0x7F879CBFA3D0, Source Call Id=152233, Destination Call Id=152234, Disposition=0x0, Tag=0xFFFFFFFFFFFFFFFF 052765: May 21 13:02:57.617: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_save_fax_config: 052766: May 21 13:02:57.617: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_save_fax_config: Fax Relay=DISABLED - MGCP Application Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=IGNORE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 052767: May 21 13:02:57.617: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_set_fax_feat_param: 052768: May 21 13:02:57.617: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_save_tty_config: 052769: May 21 13:02:57.617: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_save_tty_config: Save TTY configuration 052770: May 21 13:02:57.617: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_set_tty_feat_param: 052771: May 21 13:02:57.617: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_set_tty_feat_param: Set TTY feat parameters 052772: May 21 13:02:57.617: //152234/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 052773: May 21 13:02:57.617: cc_api_get_xcode_stream : 4981 052774: May 21 13:02:57.617: //152234/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 052775: May 21 13:02:57.618: call_info mainst_callID:0x252AA, peer_callID:0x252A9, confID:0x12955, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 052776: May 21 13:02:57.618: //152234/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 052777: May 21 13:02:57.618: stream[0]: callid:0x252AA, media_passthrough:0 052778: May 21 13:02:57.618: //152234/E15C55ECB03D/CCAPI/cc_api_bridge_done: Conference Id=0x12955, Source Interface=0x7F8793AD3208, Source Call Id=152234, Destination Call Id=152233, Disposition=0x0, Tag=0x0 052779: May 21 13:02:57.618: //152233/E15C55ECB03D/CCAPI/cc_generic_bridge_done: Conference Id=0x12955, Source Interface=0x7F8793AD3208, Source Call Id=152234, Destination Call Id=152233, Disposition=0x0, Tag=0x0 052780: May 21 13:02:57.618: //152233/E15C55ECB03D/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x12955, Destination Call Id=152234) 052781: May 21 13:02:57.618: //152234/E15C55ECB03D/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x12955, Destination Call Id=152233) 052782: May 21 13:02:57.618: //152233/E15C55ECB03D/CCAPI/ccConferenceCreate: 052783: May 21 13:02:57.618: confID:0x12955; callEntry1 callID1:0x252A9, type:6; callEntry2 callID2:0x252AA, type:9 052784: May 21 13:02:57.619: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_process_event: [state:S_CONNECT, event:E_CC_DO_CAPS_IND] 052785: May 21 13:02:57.619: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_IND 052786: May 21 13:02:57.621: //152233/E15C55ECB03D/CCAPI/cc_api_caps_ind: 052787: May 21 13:02:57.621: voice_rtp_nse_payload 0, voice_rtp_sse_payload 118 voice_sprt_payload 120 052788: May 21 13:02:57.621: //152233/E15C55ECB03D/CCAPI/cc_api_caps_ind: 052789: May 21 13:02:57.621: sprt_latency 200, sprt_retries 12 052790: May 21 13:02:57.621: //152233/E15C55ECB03D/CCAPI/cc_api_caps_ind: Destination Interface=0x7F8793AD3208, Destination Call Id=152234, Source Call Id=152233, Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=20, Signal Type=3) 052791: May 21 13:02:57.621: //152233/E15C55ECB03D/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)) 052792: May 21 13:02:57.621: //152234/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 052793: May 21 13:02:57.621: cc_api_get_xcode_stream : 4981 052794: May 21 13:02:57.622: //152234/E15C55ECB03D/CCAPI/cc_api_caps_ind: 052795: May 21 13:02:57.622: voice_rtp_nse_payload 100, voice_rtp_sse_payload 118 voice_sprt_payload 120 052796: May 21 13:02:57.622: //152234/E15C55ECB03D/CCAPI/cc_api_caps_ind: 052797: May 21 13:02:57.622: sprt_latency 200, sprt_retries 12 052798: May 21 13:02:57.622: //152234/E15C55ECB03D/CCAPI/cc_api_caps_ind: Destination Interface=0x7F879CBFA3D0, Destination Call Id=152233, Source Call Id=152234, Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=160, Signal Type=2) 052799: May 21 13:02:57.622: //152234/E15C55ECB03D/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)) 052800: May 21 13:02:57.622: //152234/E15C55ECB03D/CCAPI/cc_api_caps_ack: Destination Interface=0x7F879CBFA3D0, Destination Call Id=152233, Source Call Id=152234, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1), Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=8087) 052801: May 21 13:02:57.622: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_process_event: [state:S_CONNECT, event:E_CC_CAPS_IND] 052802: May 21 13:02:57.622: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052803: May 21 13:02:57.622: flex_dsprm_forking_mixing_support: 052804: May 21 13:02:57.622: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_ACK 052805: May 21 13:02:57.623: //152233/E15C55ECB03D/CCAPI/cc_api_caps_ack: Destination Interface=0x7F8793AD3208, Destination Call Id=152234, Source Call Id=152233, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1), Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=8087) 052806: May 21 13:02:57.623: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052807: May 21 13:02:57.626: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_call_mod_done_cb: 052808: May 21 13:02:57.627: //152233/E15C55ECB03D/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F879CBFA3D0, Call Id=152233 052809: May 21 13:02:57.627: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_save_fax_config: 052810: May 21 13:02:57.627: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_save_fax_config: Fax Relay=DISABLED - MGCP Application Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=IGNORE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 052811: May 21 13:02:57.628: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_set_fax_feat_param: 052812: May 21 13:02:57.628: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052813: May 21 13:02:57.628: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_feature_notify_cb: Feature ID=0, Feature Status=1 052814: May 21 13:02:57.629: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_reactivate_ringback: 052815: May 21 13:02:57.629: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_reactivate_ringback:exit@1291 052816: May 21 13:02:57.629: //152233/E15C55ECB03D/CCAPI/cc_api_voice_mode_event: Call Id=152233 052817: May 21 13:02:57.629: //152233/E15C55ECB03D/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x7F879EB67B38) 052818: May 21 13:02:57.630: //152233/E15C55ECB03D/CCAPI/cc_process_notify_bridge_done: Conference Id=0x12955, Call Id1=152233, Call Id2=152234 052819: May 21 13:02:57.631: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_ENABLE_TDM_RTCP 052820: May 21 13:02:57.631: MGCP Packet sent to 10.2.55.11:2427---> 200 8076372 OK I: 12956 v=0 c=IN IP4 10.0.15.178 m=audio 40484 RTP/AVP 0 99 100 a=rtpmap:99 telephone-event/8000 a=fmtp:99 0-15 a=rtpmap:100 X-NSE/8000 a=fmtp:100 192-194 <--- 052821: May 21 13:02:57.632: //152233/E15C55ECB03D/CCAPI/ccSetDigitTimeouts: Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms) 052822: May 21 13:02:57.632: //152233/E15C55ECB03D/CCAPI/ccSetDigitTimeouts: Call Entry(Inter Digit Timeout=4000(ms), Initial Digit Timeout=4000(ms)) 052823: May 21 13:02:57.633: //152233/E15C55ECB03D/CCAPI/ccRestartDigitTimeoutMsec: Digit Timeout=0, Call Id=152233 052824: May 21 13:02:57.633: //152233/E15C55ECB03D/CCAPI/ccCallReportDigits: (callID=0x252A9, digit_event=0x1, enable=TRUE, consume=FALSE) 052825: May 21 13:02:57.633: //152233/E15C55ECB03D/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152233 052826: May 21 13:02:57.634: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_report_digit_control: 052827: May 21 13:02:57.634: //152233/E15C55ECB03D/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x252A9, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) 052828: May 21 13:02:57.634: //152233/E15C55ECB03D/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152233 052829: May 21 13:02:57.635: //152233/E15C55ECB03D/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)) 052830: May 21 13:02:57.635: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_report_digit_control: Digit Reporting=TRUE 052831: May 21 13:02:57.657: MGCP Packet received from 10.2.55.11:2427---> RQNT 8076374 S0/SU1/DS1-0/5@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 5 R: D/[0-9ABCD*#] S: G/rt Q: process,loop <--- 052832: May 21 13:02:57.657: //152233/E15C55ECB03D/CCAPI/ccCallModify: Nominator=0x1000, Params=0x7F879E160610, Call Id=152233 052833: May 21 13:02:57.658: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_call_mod_done_cb: 052834: May 21 13:02:57.658: //152233/E15C55ECB03D/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F879CBFA3D0, Call Id=152233 052835: May 21 13:02:57.659: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_save_fax_config: 052836: May 21 13:02:57.659: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_save_fax_config: Fax Relay=DISABLED - MGCP Application Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=IGNORE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 052837: May 21 13:02:57.659: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_set_fax_feat_param: 052838: May 21 13:02:57.660: //152233/E15C55ECB03D/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Ring Back, Tone Direction=Network, Params=0x0, Call Id=152233 052839: May 21 13:02:57.660: MGCP Packet sent to 10.2.55.11:2427---> 200 8076374 OK <--- 052840: May 21 13:03:01.635: //152233/E15C55ECB03D/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event 052841: May 21 13:03:01.635: //152233/E15C55ECB03D/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event 052842: May 21 13:03:06.220: MGCP Packet sent to 10.2.55.11:2427---> NTFY 304023865 *@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 0 O: <--- 052843: May 21 13:03:06.221: MGCP Packet received from 10.2.55.11:2427---> 200 304023865 <--- 052844: May 21 13:03:21.219: MGCP Packet sent to 10.2.55.11:2427---> NTFY 304023866 *@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 0 O: <--- 052845: May 21 13:03:21.220: MGCP Packet received from 10.2.55.11:2427---> 200 304023866 <--- 052846: May 21 13:03:26.686: MGCP Packet received from 10.2.55.11:2427---> CRCX 8076411 S0/SU1/DS1-0/7@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a76f61000000F500006eb8 X: 7 L: p:20, a:PCMU;telephone-event, fmtp:"telephone-event 0-15", s:off, t:b8 M: recvonly R: D/[0-9ABCD*#] Q: process,loop <--- 052847: May 21 13:03:26.687: flex_modem_relay_supported:Modem Relay supported. flex_type=1htsp_allocate_if -- 052848: May 21 13:03:26.687: HTSP endpoint_info=S0/SU1/DS1-0/7, type=0, under_specified=0, service_type=2 052849: May 21 13:03:26.687: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x7F879CBFA3D0, 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=D000000001a76f61000000F500006eb8) 052850: May 21 13:03:26.688: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052851: May 21 13:03:26.688: :cc_get_feature_vsa malloc success 052852: May 21 13:03:26.688: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052853: May 21 13:03:26.688: cc_get_feature_vsa count is 13 052854: May 21 13:03:26.688: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052855: May 21 13:03:26.688: :FEATURE_VSA attributes are: feature_name:0,feature_time:140220370292684,feature_id:152235 052856: May 21 13:03:26.688: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_allocate_cdb: CDB=0x7F879F5A0C20 052857: May 21 13:03:26.688: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_insert_cdb: 052858: May 21 13:03:26.689: //152235/F2B1E569B03E/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 052859: May 21 13:03:26.689: //152235/F2B1E569B03E/CCAPI/ccCallSetContext: Context=0x7F879EB679A8 052860: May 21 13:03:26.689: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x7F8793AD3208, 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=D000000001a76f61000000F500006eb8) 052861: May 21 13:03:26.689: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052862: May 21 13:03:26.689: :cc_get_feature_vsa malloc success 052863: May 21 13:03:26.689: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052864: May 21 13:03:26.689: cc_get_feature_vsa count is 14 052865: May 21 13:03:26.689: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 052866: May 21 13:03:26.689: :FEATURE_VSA attributes are: feature_name:0,feature_time:140220370299180,feature_id:152236 052867: May 21 13:03:26.690: //152236/F2B1E569B03E/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=9, FlowMode=1 052868: May 21 13:03:26.690: //152236/F2B1E569B03E/CCAPI/ccCallSetContext: Context=0x7F879EB64EB0 052869: May 21 13:03:26.690: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_process_event: [state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ] 052870: May 21 13:03:26.690: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-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 052871: May 21 13:03:26.690: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:-1:-1/vtsp_do_call_setup_req: 052872: May 21 13:03:26.690: //152236/F2B1E569B03E/CCAPI/cc_api_call_connected: Interface=0x7F8793AD3208, Data Bitmask=0x0, Progress Indication=NULL(0), Connection Handle=0 052873: May 21 13:03:26.691: //152236/F2B1E569B03E/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) 052874: May 21 13:03:26.691: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_opened_cb: 052875: May 21 13:03:26.691: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_process_event: [state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] 052876: May 21 13:03:26.691: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/act_setup_req_pend_succ: 052877: May 21 13:03:26.691: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_request_call: Mode=9, Peer=0 052878: May 21 13:03:26.691: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_create_call_active_on_setup_req: Target Carrier ID= 052879: May 21 13:03:26.692: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_create_call_active_on_setup_req: Source Carrier ID= 052880: May 21 13:03:26.692: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_call_set_gw_outpulsed_numbers: Unable to update dial_string = 052881: May 21 13:03:26.692: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/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 052882: May 21 13:03:26.692: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_process_event: [state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING] 052883: May 21 13:03:26.692: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/act_setup_pend_proceeding: 052884: May 21 13:03:26.693: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_timer: Timer Start Time=287051940, Timer Value=25000(ms) 052885: May 21 13:03:26.693: //152235/F2B1E569B03E/CCAPI/cc_api_call_proceeding: Interface=0x7F879CBFA3D0, Progress Indication=NULL(0) 052886: May 21 13:03:26.693: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/act_setup_pend_proceeding: Peer=0, Progress Indication=0, Return Code=2 052887: May 21 13:03:26.693: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_process_event: [state:S_NULL, event:E_TSP_CONNECT] 052888: May 21 13:03:26.693: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/act_setup_req_pend_defer: Event=7 052889: May 21 13:03:26.697: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_opened_cb: 052890: May 21 13:03:26.697: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_process_event: [state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS] 052891: May 21 13:03:26.697: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/act_setup_req_wait_pend_success: Event=76 052892: May 21 13:03:26.697: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_timer_stop: Timer Stop Time=287051941 052893: May 21 13:03:26.697: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_ring_noan_timer_stop: Timer Stop Time=287051941 052894: May 21 13:03:26.697: //152235/F2B1E569B03E/CCAPI/cc_api_call_connected: Interface=0x7F879CBFA3D0, Data Bitmask=0x1, Progress Indication=DESTINATION IS NON ISDN(2), Connection Handle=0 052895: May 21 13:03:26.697: //152235/F2B1E569B03E/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) 052896: May 21 13:03:26.698: //152235/F2B1E569B03E/CCAPI/ccCallModify: Nominator=0x1000, Params=0x7F879E160C98, Call Id=152235 052897: May 21 13:03:26.698: //152235/F2B1E569B03E/CCAPI/ccCallReportDigits: (callID=0x252AB, digit_event=0x1, enable=TRUE, consume=FALSE) 052898: May 21 13:03:26.698: //152235/F2B1E569B03E/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152235 052899: May 21 13:03:26.698: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_report_digit_control: 052900: May 21 13:03:26.698: //152235/F2B1E569B03E/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x252AB, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) 052901: May 21 13:03:26.698: //152235/F2B1E569B03E/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152235 052902: May 21 13:03:26.698: //152235/F2B1E569B03E/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) 052903: May 21 13:03:26.699: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_report_digit_control: Digit Reporting=TRUE 052904: May 21 13:03:26.699: //152235/F2B1E569B03E/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFFFFFFFFFF, callID1=0x252AB, gcid=0-0-0-0, tag=0x0) 052905: May 21 13:03:26.699: //152236/F2B1E569B03E/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFFFFFFFFFF, callID2=0x252AC, gcid=0-0-0-0, tag=0x0) 052906: May 21 13:03:26.699: //152235/F2B1E569B03E/CCAPI/ccConferenceCreate: Conference Id=0xFFFFFFFFFFFFFFFF, Call Id1=152235, Call Id2=152236, Tag=0x0 052907: May 21 13:03:26.699: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_bridge_check_cb: 052908: May 21 13:03:26.699: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_bridge_check_cb:exit@1500 052909: May 21 13:03:26.699: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_bridge_status_cb: 052910: May 21 13:03:26.699: //152235/F2B1E569B03E/CCAPI/cc_api_bridge_done: Conference Id=0x12956, Source Interface=0x7F879CBFA3D0, Source Call Id=152235, Destination Call Id=152236, Disposition=0x0, Tag=0xFFFFFFFFFFFFFFFF 052911: May 21 13:03:26.699: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_save_fax_config: 052912: May 21 13:03:26.699: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_save_fax_config: Fax Relay=DISABLED - MGCP Application Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=IGNORE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 052913: May 21 13:03:26.700: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_set_fax_feat_param: 052914: May 21 13:03:26.700: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_save_tty_config: 052915: May 21 13:03:26.700: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_save_tty_config: Save TTY configuration 052916: May 21 13:03:26.700: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_set_tty_feat_param: 052917: May 21 13:03:26.700: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_set_tty_feat_param: Set TTY feat parameters 052918: May 21 13:03:26.700: //152236/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 052919: May 21 13:03:26.700: cc_api_get_xcode_stream : 4981 052920: May 21 13:03:26.700: //152236/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 052921: May 21 13:03:26.700: call_info mainst_callID:0x252AC, peer_callID:0x252AB, confID:0x12956, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 052922: May 21 13:03:26.700: //152236/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 052923: May 21 13:03:26.700: stream[0]: callid:0x252AC, media_passthrough:0 052924: May 21 13:03:26.700: //152236/F2B1E569B03E/CCAPI/cc_api_bridge_done: Conference Id=0x12956, Source Interface=0x7F8793AD3208, Source Call Id=152236, Destination Call Id=152235, Disposition=0x0, Tag=0x0 052925: May 21 13:03:26.700: //152235/F2B1E569B03E/CCAPI/cc_generic_bridge_done: Conference Id=0x12956, Source Interface=0x7F8793AD3208, Source Call Id=152236, Destination Call Id=152235, Disposition=0x0, Tag=0x0 052926: May 21 13:03:26.700: //152235/F2B1E569B03E/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x12956, Destination Call Id=152236) 052927: May 21 13:03:26.701: //152236/F2B1E569B03E/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x12956, Destination Call Id=152235) 052928: May 21 13:03:26.701: //152235/F2B1E569B03E/CCAPI/ccConferenceCreate: 052929: May 21 13:03:26.701: confID:0x12956; callEntry1 callID1:0x252AB, type:6; callEntry2 callID2:0x252AC, type:9 052930: May 21 13:03:26.701: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_process_event: [state:S_CONNECT, event:E_CC_DO_CAPS_IND] 052931: May 21 13:03:26.701: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_IND 052932: May 21 13:03:26.701: //152235/F2B1E569B03E/CCAPI/cc_api_caps_ind: 052933: May 21 13:03:26.701: voice_rtp_nse_payload 0, voice_rtp_sse_payload 118 voice_sprt_payload 120 052934: May 21 13:03:26.701: //152235/F2B1E569B03E/CCAPI/cc_api_caps_ind: 052935: May 21 13:03:26.701: sprt_latency 200, sprt_retries 12 052936: May 21 13:03:26.701: //152235/F2B1E569B03E/CCAPI/cc_api_caps_ind: Destination Interface=0x7F8793AD3208, Destination Call Id=152236, Source Call Id=152235, Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=20, Signal Type=3) 052937: May 21 13:03:26.702: //152235/F2B1E569B03E/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)) 052938: May 21 13:03:26.702: //152236/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 052939: May 21 13:03:26.702: cc_api_get_xcode_stream : 4981 052940: May 21 13:03:26.702: //152236/F2B1E569B03E/CCAPI/cc_api_caps_ind: 052941: May 21 13:03:26.702: voice_rtp_nse_payload 100, voice_rtp_sse_payload 118 voice_sprt_payload 120 052942: May 21 13:03:26.702: //152236/F2B1E569B03E/CCAPI/cc_api_caps_ind: 052943: May 21 13:03:26.702: sprt_latency 200, sprt_retries 12 052944: May 21 13:03:26.702: //152236/F2B1E569B03E/CCAPI/cc_api_caps_ind: Destination Interface=0x7F879CBFA3D0, Destination Call Id=152235, Source Call Id=152236, Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=160, Signal Type=2) 052945: May 21 13:03:26.702: //152236/F2B1E569B03E/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)) 052946: May 21 13:03:26.702: //152236/F2B1E569B03E/CCAPI/cc_api_caps_ack: Destination Interface=0x7F879CBFA3D0, Destination Call Id=152235, Source Call Id=152236, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1), Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=7) 052947: May 21 13:03:26.702: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_process_event: [state:S_CONNECT, event:E_CC_CAPS_IND] 052948: May 21 13:03:26.702: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052949: May 21 13:03:26.702: flex_dsprm_forking_mixing_support: 052950: May 21 13:03:26.702: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_ACK 052951: May 21 13:03:26.703: //152235/F2B1E569B03E/CCAPI/cc_api_caps_ack: Destination Interface=0x7F8793AD3208, Destination Call Id=152236, Source Call Id=152235, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1), Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=7) 052952: May 21 13:03:26.703: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052953: May 21 13:03:26.704: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_call_mod_done_cb: 052954: May 21 13:03:26.705: //152235/F2B1E569B03E/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F879CBFA3D0, Call Id=152235 052955: May 21 13:03:26.705: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_save_fax_config: 052956: May 21 13:03:26.705: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_save_fax_config: Fax Relay=DISABLED - MGCP Application Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=IGNORE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 052957: May 21 13:03:26.705: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_set_fax_feat_param: 052958: May 21 13:03:26.705: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052959: May 21 13:03:26.706: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_feature_notify_cb: Feature ID=0, Feature Status=1 052960: May 21 13:03:26.706: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_reactivate_ringback: 052961: May 21 13:03:26.706: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_reactivate_ringback:exit@1291 052962: May 21 13:03:26.706: //152235/F2B1E569B03E/CCAPI/cc_api_voice_mode_event: Call Id=152235 052963: May 21 13:03:26.706: //152235/F2B1E569B03E/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x7F879EB679A8) 052964: May 21 13:03:26.706: //152235/F2B1E569B03E/CCAPI/cc_process_notify_bridge_done: Conference Id=0x12956, Call Id1=152235, Call Id2=152236 052965: May 21 13:03:26.707: MGCP Packet sent to 10.2.55.11:2427---> 200 8076411 OK I: 12957 v=0 c=IN IP4 10.0.15.178 m=audio 40466 RTP/AVP 0 99 100 a=rtpmap:99 telephone-event/8000 a=fmtp:99 0-15 a=rtpmap:100 X-NSE/8000 a=fmtp:100 192-194 <--- 052966: May 21 13:03:26.707: //152235/F2B1E569B03E/CCAPI/ccSetDigitTimeouts: Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms) 052967: May 21 13:03:26.707: //152235/F2B1E569B03E/CCAPI/ccSetDigitTimeouts: Call Entry(Inter Digit Timeout=4000(ms), Initial Digit Timeout=4000(ms)) 052968: May 21 13:03:26.707: //152235/F2B1E569B03E/CCAPI/ccRestartDigitTimeoutMsec: Digit Timeout=0, Call Id=152235 052969: May 21 13:03:26.707: //152235/F2B1E569B03E/CCAPI/ccCallReportDigits: (callID=0x252AB, digit_event=0x1, enable=TRUE, consume=FALSE) 052970: May 21 13:03:26.707: //152235/F2B1E569B03E/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152235 052971: May 21 13:03:26.708: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_report_digit_control: 052972: May 21 13:03:26.708: //152235/F2B1E569B03E/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x252AB, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) 052973: May 21 13:03:26.708: //152235/F2B1E569B03E/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152235 052974: May 21 13:03:26.708: //152235/F2B1E569B03E/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)) 052975: May 21 13:03:26.708: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_report_digit_control: Digit Reporting=TRUE 052976: May 21 13:03:26.708: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_ENABLE_TDM_RTCP 052977: May 21 13:03:26.739: MGCP Packet received from 10.2.55.11:2427---> MDCX 8076412 S0/SU1/DS1-0/7@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a76f61000000F500006eb8 I: 12957 X: 7 L: p:20, a:PCMU;telephone-event, fmtp:"telephone-event 0-15", s:off, t:b8 M: sendrecv R: D/[0-9ABCD*#] S: Q: process,loop v=0 o=- 76119 0 IN EPN S0/SU1/DS1-0/7@sfsivrt01.doit.state.nm.us s=Cisco SDP 0 t=0 0 m=audio 25746 RTP/AVP 0 101 c=IN IP4 10.5.12.20 a=rtpmap:101 telephone-event a=fmtp:101 0-15 <--- 052978: May 21 13:03:26.739: //152235/F2B1E569B03E/CCAPI/ccCallModifyExtended: Nominator=0x7F879E15FC70, Params=0x7F879E160B18, Call Id=152235 052979: May 21 13:03:26.740: //152236/F2B1E569B03E/CCAPI/ccCallModify: Nominator=0x20018E30, Params=0x7F879E160488, Call Id=152236 052980: May 21 13:03:26.740: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 052981: May 21 13:03:26.740: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_call_mod_done_cb: 052982: May 21 13:03:26.740: //152235/F2B1E569B03E/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F879CBFA3D0, Call Id=152235 052983: May 21 13:03:26.741: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_save_fax_config: 052984: May 21 13:03:26.741: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_save_fax_config: 052985: May 21 13:03:26.741: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 052986: May 21 13:03:26.741: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_set_fax_feat_param: 052987: May 21 13:03:26.741: //152236/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 052988: May 21 13:03:26.741: cc_api_get_xcode_stream : 4981 052989: May 21 13:03:26.741: //152236/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 052990: May 21 13:03:26.741: cc_api_get_xcode_stream : 4981 052991: May 21 13:03:26.741: //152236/F2B1E569B03E/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F8793AD3208, Call Id=152236 052992: May 21 13:03:26.742: //152236/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 052993: May 21 13:03:26.742: call_info mainst_callID:0x252AC, peer_callID:0x252AB, confID:0x12956, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 052994: May 21 13:03:26.742: //152236/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 052995: May 21 13:03:26.742: stream[0]: callid:0x252AC, media_passthrough:0 052996: May 21 13:03:26.742: MGCP Packet sent to 10.2.55.11:2427---> 200 8076412 OK v=0 c=IN IP4 10.0.15.178 m=audio 40466 RTP/AVP 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <--- 052997: May 21 13:03:26.743: //152235/F2B1E569B03E/VTSP:(0/1/0:23):-1:1:7/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_ENABLE_TDM_RTCP 052998: May 21 13:03:30.708: //152235/F2B1E569B03E/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event 052999: May 21 13:03:30.708: //152235/F2B1E569B03E/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event 053000: May 21 13:03:36.219: MGCP Packet sent to 10.2.55.11:2427---> NTFY 304023867 *@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 0 O: <--- 053001: May 21 13:03:36.220: MGCP Packet received from 10.2.55.11:2427---> 200 304023867 <--- 053002: May 21 13:03:36.578: MGCP Packet received from 10.2.55.11:2427---> MDCX 8076426 S0/SU1/DS1-0/2@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a7668f000000F500006e9b I: 12936 X: 2 M: recvonly R: D/[0-9ABCD*#] Q: process,loop <--- 053003: May 21 13:03:36.578: //152169/F1C9BB49B01D/CCAPI/ccCallModifyExtended: Nominator=0x7F879E15FC70, Params=0x7F879E160B18, Call Id=152169 053004: May 21 13:03:36.578: //152170/F1C9BB49B01D/CCAPI/ccCallModify: Nominator=0x800, Params=0x7F879E160488, Call Id=152170 053005: May 21 13:03:36.579: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_call_mod_done_cb: 053006: May 21 13:03:36.579: //152169/F1C9BB49B01D/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F879CBFA3D0, Call Id=152169 053007: May 21 13:03:36.579: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: 053008: May 21 13:03:36.579: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: 053009: May 21 13:03:36.579: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 053010: May 21 13:03:36.579: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_set_fax_feat_param: 053011: May 21 13:03:36.579: //152170/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053012: May 21 13:03:36.579: cc_api_get_xcode_stream : 4981 053013: May 21 13:03:36.579: //152170/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053014: May 21 13:03:36.579: cc_api_get_xcode_stream : 4981 053015: May 21 13:03:36.580: //152170/F1C9BB49B01D/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F8793AD3208, Call Id=152170 053016: May 21 13:03:36.580: //152170/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 053017: May 21 13:03:36.580: call_info mainst_callID:0x2526A, peer_callID:0x25269, confID:0x12935, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 053018: May 21 13:03:36.580: //152170/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 053019: May 21 13:03:36.580: stream[0]: callid:0x2526A, media_passthrough:0 053020: May 21 13:03:36.580: MGCP Packet sent to 10.2.55.11:2427---> 200 8076426 OK <--- 053021: May 21 13:03:36.582: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_ENABLE_TDM_RTCP 053022: May 21 13:03:36.616: MGCP Packet received from 10.2.55.11:2427---> DLCX 8076427 S0/SU1/DS1-0/2@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a7668f000000F500006e9b I: 12936 X: 2 S: <--- 053023: May 21 13:03:36.616: //152169/F1C9BB49B01D/CCAPI/ccCallReportDigits: (callID=0x25269, digit_event=0x0, enable=FALSE, consume=FALSE) 053024: May 21 13:03:36.616: //152169/F1C9BB49B01D/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152169 053025: May 21 13:03:36.617: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_report_digit_control: 053026: May 21 13:03:36.617: //152169/F1C9BB49B01D/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x25269, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE) 053027: May 21 13:03:36.617: //152169/F1C9BB49B01D/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152169 053028: May 21 13:03:36.617: //152169/F1C9BB49B01D/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)) 053029: May 21 13:03:36.617: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_report_digit_control: Digit Reporting=FALSE 053030: May 21 13:03:36.617: //152170/F1C9BB49B01D/CCAPI/ccGetCallStatistics: Call Stats=0x7F879EB87778, Call Id=152170 053031: May 21 13:03:36.617: //152169/F1C9BB49B01D/CCAPI/ccConferenceDestroy: Conference Id=0x12935, Tag=0x0 053032: May 21 13:03:36.617: //152169/F1C9BB49B01D/CCAPI/ccConferenceDestroy: 053033: May 21 13:03:36.617: confID:0x12935; callEntry1 callID1:0x25269, type:6; callEntry2 callID2:0x2526A, type:9 053034: May 21 13:03:36.618: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_DISABLE_TDM_RTCP 053035: May 21 13:03:36.618: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_bridge_status_cb: 053036: May 21 13:03:36.618: //152169/F1C9BB49B01D/CCAPI/cc_api_bridge_drop_done: Conference Id=0x12935, Source Interface=0x7F879CBFA3D0, Source Call Id=152169, Destination Call Id=152170, Disposition=0x0, Tag=0x0 053037: May 21 13:03:36.618: //152170/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053038: May 21 13:03:36.618: cc_api_get_xcode_stream : 4981 053039: May 21 13:03:36.618: //152170/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053040: May 21 13:03:36.618: cc_api_get_xcode_stream : 4981 053041: May 21 13:03:36.618: //152170/F1C9BB49B01D/CCAPI/cc_api_bridge_drop_done: Conference Id=0x12935, Source Interface=0x7F8793AD3208, Source Call Id=152170, Destination Call Id=152169, Disposition=0x0, Tag=0x0 053042: May 21 13:03:36.618: //152169/F1C9BB49B01D/CCAPI/cc_generic_bridge_done: Conference Id=0x12935, Source Interface=0x7F8793AD3208, Source Call Id=152170, Destination Call Id=152169, Disposition=0x0, Tag=0x0 053043: May 21 13:03:36.619: //152169/F1C9BB49B01D/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 053044: May 21 13:03:36.620: //152169/F1C9BB49B01D/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 053045: May 21 13:03:36.620: //152169/F1C9BB49B01D/CCAPI/cc_api_get_transfer_info: Transfer Number=NULL 053046: May 21 13:03:36.620: //152170/F1C9BB49B01D/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 053047: May 21 13:03:36.620: //152170/F1C9BB49B01D/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 053048: May 21 13:03:36.620: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_process_event: [state:S_CONNECT, event:E_CC_DISCONNECT] 053049: May 21 13:03:36.620: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/act_disconnect: Cause Value=16 053050: May 21 13:03:36.620: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_ring_noan_timer_stop: Timer Stop Time=287052933 053051: May 21 13:03:36.621: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_pcm_cap_conf_tone_timer_stop: Timer Stop Time=287052933 053052: May 21 13:03:36.621: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: 053053: May 21 13:03:36.621: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: 053054: May 21 13:03:36.621: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 053055: May 21 13:03:36.621: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_set_fax_feat_param: 053056: May 21 13:03:36.622: //152170/F1C9BB49B01D/CCAPI/cc_api_nw_stats: Call Stats=0x7F879CD50DC0, Call Id=152170 053057: May 21 13:03:36.623: MGCP Packet sent to 10.2.55.11:2427---> 250 8076427 OK P: PS=43518, OS=2611080, PR=43510, OR=2610600, PL=0, JI=0, LA=0 <--- 053058: May 21 13:03:36.645: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_get_levels_done_cb: 053059: May 21 13:03:36.646: //152170/F1C9BB49B01D/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x7F8793AD3208, Tag=0x0, Call Id=152170, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 053060: May 21 13:03:36.646: //152170/F1C9BB49B01D/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 053061: May 21 13:03:36.646: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053062: May 21 13:03:36.646: :cc_free_feature_vsa freeing 7F87995CCB40 053063: May 21 13:03:36.647: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053064: May 21 13:03:36.647: vsacount in free is 13 053065: May 21 13:03:36.664: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_process_event: [state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE] 053066: May 21 13:03:36.665: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/act_dsm_dsp_stats_complete: 053067: May 21 13:03:36.665: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_timer: Timer Start Time=287052937, Timer Value=60000(ms) 053068: May 21 13:03:36.665: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_process_event: [state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF] 053069: May 21 13:03:36.665: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/act_wrelease_release: 053070: May 21 13:03:36.665: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_play_busy_timer_stop: Timer Stop Time=287052937 053071: May 21 13:03:36.665: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_timer_stop: Timer Stop Time=287052937 053072: May 21 13:03:36.665: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_do_call_history: 053073: May 21 13:03:36.665: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_do_call_history: Coder Rate=16 053074: May 21 13:03:36.665: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:1:2/vtsp_is_valid_dsm_handle: DMGR=0x7F879FA3DE70, VTSP CDB=0x7F879F59E8B8 053075: May 21 13:03:36.669: flex_dsprm_close_cleanup 053076: May 21 13:03:36.669: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:-1:-1/vtsp_dsm_closed_cb: 053077: May 21 13:03:36.670: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] 053078: May 21 13:03:36.670: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:-1:-1/act_terminate: 053079: May 21 13:03:36.670: //152169/F1C9BB49B01D/VTSP:(0/1/0:23):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=287052938 053080: May 21 13:03:36.670: //152169/F1C9BB49B01D/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x7F879CBFA3D0, Tag=0x0, Call Id=152169, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 053081: May 21 13:03:36.670: //152169/F1C9BB49B01D/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 053082: May 21 13:03:36.670: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053083: May 21 13:03:36.670: :cc_free_feature_vsa freeing 7F87995CCFA0 053084: May 21 13:03:36.670: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053085: May 21 13:03:36.670: vsacount in free is 12 053086: May 21 13:03:36.670: //-1/F1C9BB49B01D/VTSP:(0/1/0:23):-1:-1:-1/vtsp_free_cdb: CDB=0x7F879F59E8B8 053087: May 21 13:03:51.220: MGCP Packet sent to 10.2.55.11:2427---> NTFY 304023868 *@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 0 O: <--- 053088: May 21 13:03:51.220: MGCP Packet received from 10.2.55.11:2427---> 200 304023868 <--- 053089: May 21 13:03:58.639: MGCP Packet received from 10.2.55.11:2427---> DLCX 8076455 S0/SU1/DS1-0/5@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a76f2a000000F580007163 I: 12956 X: 5 S: <--- 053090: May 21 13:03:58.639: //152233/E15C55ECB03D/CCAPI/ccCallReportDigits: (callID=0x252A9, digit_event=0x0, enable=FALSE, consume=FALSE) 053091: May 21 13:03:58.639: //152233/E15C55ECB03D/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152233 053092: May 21 13:03:58.639: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_report_digit_control: 053093: May 21 13:03:58.639: //152233/E15C55ECB03D/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x252A9, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE) 053094: May 21 13:03:58.639: //152233/E15C55ECB03D/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152233 053095: May 21 13:03:58.639: //152233/E15C55ECB03D/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)) 053096: May 21 13:03:58.639: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_report_digit_control: Digit Reporting=FALSE 053097: May 21 13:03:58.640: //152233/E15C55ECB03D/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Null, Tone Direction=Network, Params=0x0, Call Id=152233 053098: May 21 13:03:58.640: //152234/E15C55ECB03D/CCAPI/ccGetCallStatistics: Call Stats=0x7F879EB85E58, Call Id=152234 053099: May 21 13:03:58.640: //152233/E15C55ECB03D/CCAPI/ccConferenceDestroy: Conference Id=0x12955, Tag=0x0 053100: May 21 13:03:58.640: //152233/E15C55ECB03D/CCAPI/ccConferenceDestroy: 053101: May 21 13:03:58.640: confID:0x12955; callEntry1 callID1:0x252A9, type:6; callEntry2 callID2:0x252AA, type:9 053102: May 21 13:03:58.640: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_DISABLE_TDM_RTCP 053103: May 21 13:03:58.640: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_bridge_status_cb: 053104: May 21 13:03:58.640: //152233/E15C55ECB03D/CCAPI/cc_api_bridge_drop_done: Conference Id=0x12955, Source Interface=0x7F879CBFA3D0, Source Call Id=152233, Destination Call Id=152234, Disposition=0x0, Tag=0x0 053105: May 21 13:03:58.641: //152234/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053106: May 21 13:03:58.641: cc_api_get_xcode_stream : 4981 053107: May 21 13:03:58.641: //152234/E15C55ECB03D/CCAPI/cc_api_bridge_drop_done: Conference Id=0x12955, Source Interface=0x7F8793AD3208, Source Call Id=152234, Destination Call Id=152233, Disposition=0x0, Tag=0x0 053108: May 21 13:03:58.641: //152233/E15C55ECB03D/CCAPI/cc_generic_bridge_done: Conference Id=0x12955, Source Interface=0x7F8793AD3208, Source Call Id=152234, Destination Call Id=152233, Disposition=0x0, Tag=0x0 053109: May 21 13:03:58.641: //152233/E15C55ECB03D/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 053110: May 21 13:03:58.641: //152233/E15C55ECB03D/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 053111: May 21 13:03:58.641: //152233/E15C55ECB03D/CCAPI/cc_api_get_transfer_info: Transfer Number=NULL 053112: May 21 13:03:58.641: //152234/E15C55ECB03D/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 053113: May 21 13:03:58.641: //152234/E15C55ECB03D/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 053114: May 21 13:03:58.641: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_process_event: [state:S_CONNECT, event:E_CC_DISCONNECT] 053115: May 21 13:03:58.642: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/act_disconnect: Cause Value=16 053116: May 21 13:03:58.642: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_ring_noan_timer_stop: Timer Stop Time=287055135 053117: May 21 13:03:58.642: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_pcm_cap_conf_tone_timer_stop: Timer Stop Time=287055135 053118: May 21 13:03:58.642: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_save_fax_config: 053119: May 21 13:03:58.642: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_save_fax_config: Fax Relay=DISABLED - MGCP Application Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=IGNORE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 053120: May 21 13:03:58.642: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_set_fax_feat_param: 053121: May 21 13:03:58.644: //152234/E15C55ECB03D/CCAPI/cc_api_nw_stats: Call Stats=0x7F879CD50DC0, Call Id=152234 053122: May 21 13:03:58.645: MGCP Packet sent to 10.2.55.11:2427---> 250 8076455 OK P: PS=0, OS=0, PR=0, OR=0, PL=0, JI=0, LA=0 <--- 053123: May 21 13:03:58.686: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_dsm_get_levels_done_cb: 053124: May 21 13:03:58.687: //152234/E15C55ECB03D/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x7F8793AD3208, Tag=0x0, Call Id=152234, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 053125: May 21 13:03:58.687: //152234/E15C55ECB03D/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 053126: May 21 13:03:58.688: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053127: May 21 13:03:58.688: :cc_free_feature_vsa freeing 7F87995C9180 053128: May 21 13:03:58.688: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053129: May 21 13:03:58.688: vsacount in free is 11 053130: May 21 13:03:58.705: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_process_event: [state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE] 053131: May 21 13:03:58.705: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/act_dsm_dsp_stats_complete: 053132: May 21 13:03:58.705: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_timer: Timer Start Time=287055141, Timer Value=60000(ms) 053133: May 21 13:03:58.705: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_process_event: [state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF] 053134: May 21 13:03:58.705: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/act_wrelease_release: 053135: May 21 13:03:58.705: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_play_busy_timer_stop: Timer Stop Time=287055141 053136: May 21 13:03:58.705: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_timer_stop: Timer Stop Time=287055141 053137: May 21 13:03:58.705: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_do_call_history: 053138: May 21 13:03:58.706: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_do_call_history: Coder Rate=5 053139: May 21 13:03:58.706: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:1:5/vtsp_is_valid_dsm_handle: DMGR=0x7F879F8FE558, VTSP CDB=0x7F879F59F8D0 053140: May 21 13:03:58.710: flex_dsprm_close_cleanup 053141: May 21 13:03:58.710: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:-1:-1/vtsp_dsm_closed_cb: 053142: May 21 13:03:58.710: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] 053143: May 21 13:03:58.710: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:-1:-1/act_terminate: 053144: May 21 13:03:58.710: //152233/E15C55ECB03D/VTSP:(0/1/0:23):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=287055142 053145: May 21 13:03:58.710: //152233/E15C55ECB03D/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x7F879CBFA3D0, Tag=0x0, Call Id=152233, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 053146: May 21 13:03:58.711: //152233/E15C55ECB03D/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 053147: May 21 13:03:58.711: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053148: May 21 13:03:58.711: :cc_free_feature_vsa freeing 7F87995CB020 053149: May 21 13:03:58.711: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053150: May 21 13:03:58.711: vsacount in free is 10 053151: May 21 13:03:58.711: //-1/E15C55ECB03D/VTSP:(0/1/0:23):-1:-1:-1/vtsp_free_cdb: CDB=0x7F879F59F8D0 053152: May 21 13:04:06.219: MGCP Packet sent to 10.2.55.11:2427---> NTFY 304023869 *@sfsivrt01.doit.state.nm.us MGCP 0.1 X: 0 O: <--- 053153: May 21 13:04:06.220: MGCP Packet received from 10.2.55.11:2427---> 200 304023869 <--- 053154: May 21 13:04:17.935: MGCP Packet received from 10.2.55.11:2427---> MDCX 8076484 S0/SU1/DS1-0/6@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a76e0f000000F500006eb6 I: 12954 X: 6 M: recvonly R: D/[0-9ABCD*#] Q: process,loop <--- 053155: May 21 13:04:17.935: //152229/95895361B03B/CCAPI/ccCallModifyExtended: Nominator=0x7F879E15FC70, Params=0x7F879E160B18, Call Id=152229 053156: May 21 13:04:17.936: //152230/95895361B03B/CCAPI/ccCallModify: Nominator=0x800, Params=0x7F879E160488, Call Id=152230 053157: May 21 13:04:17.936: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_call_mod_done_cb: 053158: May 21 13:04:17.936: //152229/95895361B03B/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F879CBFA3D0, Call Id=152229 053159: May 21 13:04:17.937: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_save_fax_config: 053160: May 21 13:04:17.937: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_save_fax_config: 053161: May 21 13:04:17.937: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 053162: May 21 13:04:17.937: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_set_fax_feat_param: 053163: May 21 13:04:17.937: //152230/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053164: May 21 13:04:17.937: cc_api_get_xcode_stream : 4981 053165: May 21 13:04:17.937: //152230/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053166: May 21 13:04:17.937: cc_api_get_xcode_stream : 4981 053167: May 21 13:04:17.938: //152230/95895361B03B/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F8793AD3208, Call Id=152230 053168: May 21 13:04:17.938: //152230/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 053169: May 21 13:04:17.938: call_info mainst_callID:0x252A6, peer_callID:0x252A5, confID:0x12953, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 053170: May 21 13:04:17.938: //152230/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 053171: May 21 13:04:17.938: stream[0]: callid:0x252A6, media_passthrough:0 053172: May 21 13:04:17.939: MGCP Packet sent to 10.2.55.11:2427---> 200 8076484 OK <--- 053173: May 21 13:04:17.939: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_ENABLE_TDM_RTCP 053174: May 21 13:04:17.955: MGCP Packet received from 10.2.55.11:2427---> DLCX 8076486 S0/SU1/DS1-0/6@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a76e0f000000F500006eb6 I: 12954 X: 6 S: <--- 053175: May 21 13:04:17.956: //152229/95895361B03B/CCAPI/ccCallReportDigits: (callID=0x252A5, digit_event=0x0, enable=FALSE, consume=FALSE) 053176: May 21 13:04:17.956: //152229/95895361B03B/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152229 053177: May 21 13:04:17.956: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_report_digit_control: 053178: May 21 13:04:17.956: //152229/95895361B03B/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x252A5, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE) 053179: May 21 13:04:17.956: //152229/95895361B03B/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152229 053180: May 21 13:04:17.956: //152229/95895361B03B/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)) 053181: May 21 13:04:17.956: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_report_digit_control: Digit Reporting=FALSE 053182: May 21 13:04:17.956: //152230/95895361B03B/CCAPI/ccGetCallStatistics: Call Stats=0x7F879EB855F8, Call Id=152230 053183: May 21 13:04:17.956: //152229/95895361B03B/CCAPI/ccConferenceDestroy: Conference Id=0x12953, Tag=0x0 053184: May 21 13:04:17.956: //152229/95895361B03B/CCAPI/ccConferenceDestroy: 053185: May 21 13:04:17.957: confID:0x12953; callEntry1 callID1:0x252A5, type:6; callEntry2 callID2:0x252A6, type:9 053186: May 21 13:04:17.957: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_DISABLE_TDM_RTCP 053187: May 21 13:04:17.957: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_bridge_status_cb: 053188: May 21 13:04:17.957: //152229/95895361B03B/CCAPI/cc_api_bridge_drop_done: Conference Id=0x12953, Source Interface=0x7F879CBFA3D0, Source Call Id=152229, Destination Call Id=152230, Disposition=0x0, Tag=0x0 053189: May 21 13:04:17.957: //152230/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053190: May 21 13:04:17.957: cc_api_get_xcode_stream : 4981 053191: May 21 13:04:17.957: //152230/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053192: May 21 13:04:17.957: cc_api_get_xcode_stream : 4981 053193: May 21 13:04:17.957: //152230/95895361B03B/CCAPI/cc_api_bridge_drop_done: Conference Id=0x12953, Source Interface=0x7F8793AD3208, Source Call Id=152230, Destination Call Id=152229, Disposition=0x0, Tag=0x0 053194: May 21 13:04:17.957: //152229/95895361B03B/CCAPI/cc_generic_bridge_done: Conference Id=0x12953, Source Interface=0x7F8793AD3208, Source Call Id=152230, Destination Call Id=152229, Disposition=0x0, Tag=0x0 053195: May 21 13:04:17.957: //152229/95895361B03B/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 053196: May 21 13:04:17.958: //152229/95895361B03B/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 053197: May 21 13:04:17.958: //152229/95895361B03B/CCAPI/cc_api_get_transfer_info: Transfer Number=NULL 053198: May 21 13:04:17.958: //152230/95895361B03B/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 053199: May 21 13:04:17.958: //152230/95895361B03B/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 053200: May 21 13:04:17.958: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_process_event: [state:S_CONNECT, event:E_CC_DISCONNECT] 053201: May 21 13:04:17.958: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/act_disconnect: Cause Value=16 053202: May 21 13:04:17.958: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_ring_noan_timer_stop: Timer Stop Time=287057067 053203: May 21 13:04:17.959: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_pcm_cap_conf_tone_timer_stop: Timer Stop Time=287057067 053204: May 21 13:04:17.959: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_save_fax_config: 053205: May 21 13:04:17.959: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_save_fax_config: 053206: May 21 13:04:17.959: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 053207: May 21 13:04:17.959: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_set_fax_feat_param: 053208: May 21 13:04:17.961: //152230/95895361B03B/CCAPI/cc_api_nw_stats: Call Stats=0x7F879CD50DC0, Call Id=152230 053209: May 21 13:04:17.962: MGCP Packet sent to 10.2.55.11:2427---> 250 8076486 OK P: PS=10375, OS=2075000, PR=10363, OR=2072600, PL=0, JI=0, LA=0 <--- 053210: May 21 13:04:17.976: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_dsm_get_levels_done_cb: 053211: May 21 13:04:17.977: //152230/95895361B03B/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x7F8793AD3208, Tag=0x0, Call Id=152230, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 053212: May 21 13:04:17.978: //152230/95895361B03B/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 053213: May 21 13:04:17.978: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053214: May 21 13:04:17.978: :cc_free_feature_vsa freeing 7F87995C8C40 053215: May 21 13:04:17.978: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053216: May 21 13:04:17.978: vsacount in free is 9 053217: May 21 13:04:18.015: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_process_event: [state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE] 053218: May 21 13:04:18.015: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/act_dsm_dsp_stats_complete: 053219: May 21 13:04:18.015: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_timer: Timer Start Time=287057072, Timer Value=60000(ms) 053220: May 21 13:04:18.015: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_process_event: [state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF] 053221: May 21 13:04:18.016: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/act_wrelease_release: 053222: May 21 13:04:18.016: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_play_busy_timer_stop: Timer Stop Time=287057073 053223: May 21 13:04:18.016: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_timer_stop: Timer Stop Time=287057073 053224: May 21 13:04:18.016: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_do_call_history: 053225: May 21 13:04:18.016: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_do_call_history: Coder Rate=5 053226: May 21 13:04:18.016: //152229/95895361B03B/VTSP:(0/1/0:23):-1:1:6/vtsp_is_valid_dsm_handle: DMGR=0x7F879FAB15E0, VTSP CDB=0x7F879F5A2F88 053227: May 21 13:04:18.020: flex_dsprm_close_cleanup 053228: May 21 13:04:18.020: //152229/95895361B03B/VTSP:(0/1/0:23):-1:-1:-1/vtsp_dsm_closed_cb: 053229: May 21 13:04:18.021: //152229/95895361B03B/VTSP:(0/1/0:23):-1:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] 053230: May 21 13:04:18.021: //152229/95895361B03B/VTSP:(0/1/0:23):-1:-1:-1/act_terminate: 053231: May 21 13:04:18.021: //152229/95895361B03B/VTSP:(0/1/0:23):-1:-1:-1/vtsp_timer_stop: Timer Stop Time=287057073 053232: May 21 13:04:18.021: //152229/95895361B03B/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x7F879CBFA3D0, Tag=0x0, Call Id=152229, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 053233: May 21 13:04:18.021: //152229/95895361B03B/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 053234: May 21 13:04:18.021: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053235: May 21 13:04:18.021: :cc_free_feature_vsa freeing 7F87995CD5C0 053236: May 21 13:04:18.021: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 053237: May 21 13:04:18.021: vsacount in free is 8 053238: May 21 13:04:18.021: //-1/95895361B03B/VTSP:(0/1/0:23):-1:-1:-1/vtsp_free_cdb: CDB=0x7F879F5A2F88 053239: May 21 13:04:19.583: MGCP Packet received from 10.2.55.11:2427---> CRCX 8076488 S0/SU1/DS1-0/2@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a76fbf000000F500006eb9 X: 2 L: p:20, a:PCMU;telephone-event, fmtp:"telephone-event 0-15", s:off, t:b8 M: recvonly R: D/[0-9ABCD*#] Q: process,loop <--- 053240: May 21 13:04:19.583: flex_modem_relay_supported:Modem Relay supported. flex_type=1htsp_allocate_if -- 053241: May 21 13:04:19.583: HTSP endpoint_info=S0/SU1/DS1-0/2, type=0, under_specified=0, service_type=2 053242: May 21 13:04:19.584: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x7F879CBFA3D0, 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=D000000001a76fbf000000F500006eb9) 053243: May 21 13:04:19.584: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 053244: May 21 13:04:19.584: :cc_get_feature_vsa malloc success 053245: May 21 13:04:19.584: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 053246: May 21 13:04:19.584: cc_get_feature_vsa count is 9 053247: May 21 13:04:19.584: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 053248: May 21 13:04:19.584: :FEATURE_VSA attributes are: feature_name:0,feature_time:140220370310604,feature_id:152237 053249: May 21 13:04:19.584: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_allocate_cdb: CDB=0x7F879F5A1C38 053250: May 21 13:04:19.584: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_insert_cdb: 053251: May 21 13:04:19.584: //152237/12392F78B03F/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 053252: May 21 13:04:19.584: //152237/12392F78B03F/CCAPI/ccCallSetContext: Context=0x7F879EB67D90 053253: May 21 13:04:19.585: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x7F8793AD3208, 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=D000000001a76fbf000000F500006eb9) 053254: May 21 13:04:19.585: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 053255: May 21 13:04:19.585: :cc_get_feature_vsa malloc success 053256: May 21 13:04:19.585: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 053257: May 21 13:04:19.585: cc_get_feature_vsa count is 10 053258: May 21 13:04:19.585: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 053259: May 21 13:04:19.585: :FEATURE_VSA attributes are: feature_name:0,feature_time:140220370291788,feature_id:152238 053260: May 21 13:04:19.585: //152238/12392F78B03F/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=9, FlowMode=1 053261: May 21 13:04:19.585: //152238/12392F78B03F/CCAPI/ccCallSetContext: Context=0x7F879EB65298 053262: May 21 13:04:19.585: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:23):-1:-1:-1/vtsp_process_event: [state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ] 053263: May 21 13:04:19.586: //152237/12392F78B03F/VTSP:(0/1/0:23):-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 053264: May 21 13:04:19.586: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:-1:-1/vtsp_do_call_setup_req: 053265: May 21 13:04:19.586: //152238/12392F78B03F/CCAPI/cc_api_call_connected: Interface=0x7F8793AD3208, Data Bitmask=0x0, Progress Indication=NULL(0), Connection Handle=0 053266: May 21 13:04:19.586: //152238/12392F78B03F/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) 053267: May 21 13:04:19.587: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_opened_cb: 053268: May 21 13:04:19.587: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_process_event: [state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] 053269: May 21 13:04:19.587: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/act_setup_req_pend_succ: 053270: May 21 13:04:19.587: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_request_call: Mode=9, Peer=0 053271: May 21 13:04:19.587: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_create_call_active_on_setup_req: Target Carrier ID= 053272: May 21 13:04:19.587: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_create_call_active_on_setup_req: Source Carrier ID= 053273: May 21 13:04:19.587: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_call_set_gw_outpulsed_numbers: Unable to update dial_string = 053274: May 21 13:04:19.588: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/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 053275: May 21 13:04:19.588: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_process_event: [state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING] 053276: May 21 13:04:19.588: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/act_setup_pend_proceeding: 053277: May 21 13:04:19.588: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_timer: Timer Start Time=287057230, Timer Value=25000(ms) 053278: May 21 13:04:19.588: //152237/12392F78B03F/CCAPI/cc_api_call_proceeding: Interface=0x7F879CBFA3D0, Progress Indication=NULL(0) 053279: May 21 13:04:19.588: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/act_setup_pend_proceeding: Peer=0, Progress Indication=0, Return Code=2 053280: May 21 13:04:19.589: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_process_event: [state:S_NULL, event:E_TSP_CONNECT] 053281: May 21 13:04:19.589: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/act_setup_req_pend_defer: Event=7 053282: May 21 13:04:19.592: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_opened_cb: 053283: May 21 13:04:19.593: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_process_event: [state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS] 053284: May 21 13:04:19.593: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/act_setup_req_wait_pend_success: Event=76 053285: May 21 13:04:19.593: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_timer_stop: Timer Stop Time=287057230 053286: May 21 13:04:19.593: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_ring_noan_timer_stop: Timer Stop Time=287057230 053287: May 21 13:04:19.593: //152237/12392F78B03F/CCAPI/cc_api_call_connected: Interface=0x7F879CBFA3D0, Data Bitmask=0x1, Progress Indication=DESTINATION IS NON ISDN(2), Connection Handle=0 053288: May 21 13:04:19.593: //152237/12392F78B03F/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) 053289: May 21 13:04:19.593: //152237/12392F78B03F/CCAPI/ccCallModify: Nominator=0x1000, Params=0x7F879E160C98, Call Id=152237 053290: May 21 13:04:19.594: //152237/12392F78B03F/CCAPI/ccCallReportDigits: (callID=0x252AD, digit_event=0x1, enable=TRUE, consume=FALSE) 053291: May 21 13:04:19.594: //152237/12392F78B03F/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152237 053292: May 21 13:04:19.594: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_report_digit_control: 053293: May 21 13:04:19.594: //152237/12392F78B03F/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x252AD, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) 053294: May 21 13:04:19.594: //152237/12392F78B03F/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152237 053295: May 21 13:04:19.594: //152237/12392F78B03F/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms)) 053296: May 21 13:04:19.594: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_report_digit_control: Digit Reporting=TRUE 053297: May 21 13:04:19.594: //152237/12392F78B03F/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFFFFFFFFFF, callID1=0x252AD, gcid=0-0-0-0, tag=0x0) 053298: May 21 13:04:19.595: //152238/12392F78B03F/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFFFFFFFFFF, callID2=0x252AE, gcid=0-0-0-0, tag=0x0) 053299: May 21 13:04:19.595: //152237/12392F78B03F/CCAPI/ccConferenceCreate: Conference Id=0xFFFFFFFFFFFFFFFF, Call Id1=152237, Call Id2=152238, Tag=0x0 053300: May 21 13:04:19.595: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_bridge_check_cb: 053301: May 21 13:04:19.595: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_bridge_check_cb:exit@1500 053302: May 21 13:04:19.595: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_bridge_status_cb: 053303: May 21 13:04:19.595: //152237/12392F78B03F/CCAPI/cc_api_bridge_done: Conference Id=0x12957, Source Interface=0x7F879CBFA3D0, Source Call Id=152237, Destination Call Id=152238, Disposition=0x0, Tag=0xFFFFFFFFFFFFFFFF 053304: May 21 13:04:19.595: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: 053305: May 21 13:04:19.595: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: Fax Relay=DISABLED - MGCP Application Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=IGNORE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 053306: May 21 13:04:19.595: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_set_fax_feat_param: 053307: May 21 13:04:19.595: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_tty_config: 053308: May 21 13:04:19.595: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_tty_config: Save TTY configuration 053309: May 21 13:04:19.595: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_set_tty_feat_param: 053310: May 21 13:04:19.596: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_set_tty_feat_param: Set TTY feat parameters 053311: May 21 13:04:19.596: //152238/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053312: May 21 13:04:19.596: cc_api_get_xcode_stream : 4981 053313: May 21 13:04:19.596: //152238/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 053314: May 21 13:04:19.596: call_info mainst_callID:0x252AE, peer_callID:0x252AD, confID:0x12957, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 053315: May 21 13:04:19.596: //152238/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 053316: May 21 13:04:19.596: stream[0]: callid:0x252AE, media_passthrough:0 053317: May 21 13:04:19.596: //152238/12392F78B03F/CCAPI/cc_api_bridge_done: Conference Id=0x12957, Source Interface=0x7F8793AD3208, Source Call Id=152238, Destination Call Id=152237, Disposition=0x0, Tag=0x0 053318: May 21 13:04:19.596: //152237/12392F78B03F/CCAPI/cc_generic_bridge_done: Conference Id=0x12957, Source Interface=0x7F8793AD3208, Source Call Id=152238, Destination Call Id=152237, Disposition=0x0, Tag=0x0 053319: May 21 13:04:19.596: //152237/12392F78B03F/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x12957, Destination Call Id=152238) 053320: May 21 13:04:19.596: //152238/12392F78B03F/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x12957, Destination Call Id=152237) 053321: May 21 13:04:19.596: //152237/12392F78B03F/CCAPI/ccConferenceCreate: 053322: May 21 13:04:19.596: confID:0x12957; callEntry1 callID1:0x252AD, type:6; callEntry2 callID2:0x252AE, type:9 053323: May 21 13:04:19.596: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_process_event: [state:S_CONNECT, event:E_CC_DO_CAPS_IND] 053324: May 21 13:04:19.597: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_IND 053325: May 21 13:04:19.597: //152237/12392F78B03F/CCAPI/cc_api_caps_ind: 053326: May 21 13:04:19.597: voice_rtp_nse_payload 0, voice_rtp_sse_payload 118 voice_sprt_payload 120 053327: May 21 13:04:19.597: //152237/12392F78B03F/CCAPI/cc_api_caps_ind: 053328: May 21 13:04:19.597: sprt_latency 200, sprt_retries 12 053329: May 21 13:04:19.597: //152237/12392F78B03F/CCAPI/cc_api_caps_ind: Destination Interface=0x7F8793AD3208, Destination Call Id=152238, Source Call Id=152237, Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=20, Signal Type=3) 053330: May 21 13:04:19.597: //152237/12392F78B03F/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)) 053331: May 21 13:04:19.597: //152238/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053332: May 21 13:04:19.597: cc_api_get_xcode_stream : 4981 053333: May 21 13:04:19.597: //152238/12392F78B03F/CCAPI/cc_api_caps_ind: 053334: May 21 13:04:19.597: voice_rtp_nse_payload 100, voice_rtp_sse_payload 118 voice_sprt_payload 120 053335: May 21 13:04:19.597: //152238/12392F78B03F/CCAPI/cc_api_caps_ind: 053336: May 21 13:04:19.597: sprt_latency 200, sprt_retries 12 053337: May 21 13:04:19.597: //152238/12392F78B03F/CCAPI/cc_api_caps_ind: Destination Interface=0x7F879CBFA3D0, Destination Call Id=152237, Source Call Id=152238, Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=160, Signal Type=2) 053338: May 21 13:04:19.597: //152238/12392F78B03F/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)) 053339: May 21 13:04:19.597: //152238/12392F78B03F/CCAPI/cc_api_caps_ack: Destination Interface=0x7F879CBFA3D0, Destination Call Id=152237, Source Call Id=152238, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1), Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=6502) 053340: May 21 13:04:19.597: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_process_event: [state:S_CONNECT, event:E_CC_CAPS_IND] 053341: May 21 13:04:19.597: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 053342: May 21 13:04:19.597: flex_dsprm_forking_mixing_support: 053343: May 21 13:04:19.597: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_ACK 053344: May 21 13:04:19.597: //152237/12392F78B03F/CCAPI/cc_api_caps_ack: Destination Interface=0x7F8793AD3208, Destination Call Id=152238, Source Call Id=152237, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1), Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=6502) 053345: May 21 13:04:19.598: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 053346: May 21 13:04:19.600: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_call_mod_done_cb: 053347: May 21 13:04:19.600: //152237/12392F78B03F/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F879CBFA3D0, Call Id=152237 053348: May 21 13:04:19.600: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: 053349: May 21 13:04:19.600: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: Fax Relay=DISABLED - MGCP Application Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=IGNORE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 053350: May 21 13:04:19.600: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_set_fax_feat_param: 053351: May 21 13:04:19.600: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 053352: May 21 13:04:19.602: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_feature_notify_cb: Feature ID=0, Feature Status=1 053353: May 21 13:04:19.602: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_reactivate_ringback: 053354: May 21 13:04:19.602: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_reactivate_ringback:exit@1291 053355: May 21 13:04:19.602: //152237/12392F78B03F/CCAPI/cc_api_voice_mode_event: Call Id=152237 053356: May 21 13:04:19.602: //152237/12392F78B03F/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x7F879EB67D90) 053357: May 21 13:04:19.602: //152237/12392F78B03F/CCAPI/cc_process_notify_bridge_done: Conference Id=0x12957, Call Id1=152237, Call Id2=152238 053358: May 21 13:04:19.602: MGCP Packet sent to 10.2.55.11:2427---> 200 8076488 OK I: 12958 v=0 c=IN IP4 10.0.15.178 m=audio 40452 RTP/AVP 0 99 100 a=rtpmap:99 telephone-event/8000 a=fmtp:99 0-15 a=rtpmap:100 X-NSE/8000 a=fmtp:100 192-194 <--- 053359: May 21 13:04:19.603: //152237/12392F78B03F/CCAPI/ccSetDigitTimeouts: Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms) 053360: May 21 13:04:19.604: //152237/12392F78B03F/CCAPI/ccSetDigitTimeouts: Call Entry(Inter Digit Timeout=4000(ms), Initial Digit Timeout=4000(ms)) 053361: May 21 13:04:19.604: //152237/12392F78B03F/CCAPI/ccRestartDigitTimeoutMsec: Digit Timeout=0, Call Id=152237 053362: May 21 13:04:19.604: //152237/12392F78B03F/CCAPI/ccCallReportDigits: (callID=0x252AD, digit_event=0x1, enable=TRUE, consume=FALSE) 053363: May 21 13:04:19.604: //152237/12392F78B03F/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=152237 053364: May 21 13:04:19.604: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_report_digit_control: 053365: May 21 13:04:19.604: //152237/12392F78B03F/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x7F879CBFA3D0, callID=0x252AD, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) 053366: May 21 13:04:19.604: //152237/12392F78B03F/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x7F879CBFA3D0, Call Id=152237 053367: May 21 13:04:19.604: //152237/12392F78B03F/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)) 053368: May 21 13:04:19.604: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_report_digit_control: Digit Reporting=TRUE 053369: May 21 13:04:19.605: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_ENABLE_TDM_RTCP 053370: May 21 13:04:19.641: MGCP Packet received from 10.2.55.11:2427---> MDCX 8076489 S0/SU1/DS1-0/2@sfsivrt01.doit.state.nm.us MGCP 0.1 C: D000000001a76fbf000000F500006eb9 I: 12958 X: 2 L: p:20, a:PCMU;telephone-event, fmtp:"telephone-event 0-15", s:off, t:b8 M: sendrecv R: D/[0-9ABCD*#] S: Q: process,loop v=0 o=- 76120 0 IN EPN S0/SU1/DS1-0/2@sfsivrt01.doit.state.nm.us s=Cisco SDP 0 t=0 0 m=audio 21140 RTP/AVP 0 101 c=IN IP4 10.5.12.109 a=rtpmap:101 telephone-event a=fmtp:101 0-15 <--- 053371: May 21 13:04:19.641: //152237/12392F78B03F/CCAPI/ccCallModifyExtended: Nominator=0x7F879E15FC70, Params=0x7F879E160B18, Call Id=152237 053372: May 21 13:04:19.641: //152238/12392F78B03F/CCAPI/ccCallModify: Nominator=0x20018E30, Params=0x7F879E160488, Call Id=152238 053373: May 21 13:04:19.642: mars_flex_dsprm_current_codec_comp:DSP:0 FLEX Complexity Codec 053374: May 21 13:04:19.645: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_call_mod_done_cb: 053375: May 21 13:04:19.646: //152237/12392F78B03F/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F879CBFA3D0, Call Id=152237 053376: May 21 13:04:19.646: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: 053377: May 21 13:04:19.646: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: 053378: May 21 13:04:19.646: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=MGCP Call Type 053379: May 21 13:04:19.646: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_set_fax_feat_param: 053380: May 21 13:04:19.646: //152238/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053381: May 21 13:04:19.646: cc_api_get_xcode_stream : 4981 053382: May 21 13:04:19.646: //152238/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 053383: May 21 13:04:19.646: cc_api_get_xcode_stream : 4981 053384: May 21 13:04:19.647: //152238/12392F78B03F/CCAPI/cc_api_call_modify_done: Result=0, Interface=0x7F8793AD3208, Call Id=152238 053385: May 21 13:04:19.647: //152238/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 053386: May 21 13:04:19.647: call_info mainst_callID:0x252AE, peer_callID:0x252AD, confID:0x12957, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 053387: May 21 13:04:19.648: //152238/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: 053388: May 21 13:04:19.648: stream[0]: callid:0x252AE, media_passthrough:0 053389: May 21 13:04:19.648: MGCP Packet sent to 10.2.55.11:2427---> 200 8076489 OK v=0 c=IN IP4 10.0.15.178 m=audio 40452 RTP/AVP 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <--- 053390: May 21 13:04:19.649: //152237/12392F78B03F/VTSP:(0/1/0:23):-1:1:2/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_ENABLE_TDM_RTCP 053391: May 21 13:04:23.604: //152237/12392F78B03F/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event 053392: May 21 13:04:23.604: //152237/12392F78B03F/CCAPI/cc_handle_inter_digit_timer: Generate inter-digit timeout CC_EV_CALL_DIGIT_END event sfsivrt01#