vg-gib-001# vg-gib-001# vg-gib-001#ter mon vg-gib-001#eplaces,sdp-anat Content-Type: application/sdp Content-Length: 365 v=0 o=CiscoSystemsSIP-GW-UserAgent 3806 9874 IN IP4 10.50.66.1 s=SIP Call c=IN IP4 10.50.66.1 t=0 0 m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3 c=IN IP4 10.50.66.1 m=image 0 udptl t38 c=IN IP4 10.50.66.1 a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:200 a=T38FaxMaxDatagram:320 a=T38FaxUdpEC:t38UDPRedundancy Apr 10 09:55:01.943: ISDN Se0/1/0:15 Q931: RX <- SETUP pd = 8 callref = 0x0001 Sending Complete Bearer Capability i = 0x9090A3 Standard = CCITT Transfer Capability = 3.1kHz Audio Transfer Mode = Circuit Transfer Rate = 64 kbit/s Channel ID i = 0xA98381 Exclusive, Channel 1 Progress Ind i = 0x8381 - Call not end-to-end ISDN, may have in-band info Progress Ind i = 0x8483 - Origination address is non-ISDN Calling Party Number i = 0x1183, '41797329888' Plan:ISDN, Type:International Called Party Number i = 0xC1, '02501' Plan:ISDN, Type:Subscriber(local) Apr 10 09:55:01.944: ISDN Se0/1/0:15 Q931: Received SETUP callref = 0x8001 callID = 0x0225 switch = primary-net5 interface = User Apr 10 09:55:01.944: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:15):-1:-1:-1/vtsp_allocate_cdb: CDB=0x7FFDB2DC8A90 Apr 10 09:55:01.944: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:15):-1:-1:-1/vtsp_do_call_setup_ind: Event=E_TSP_SETUP_IND Progress Indication=1, CarrierIDCode=, Info Trans Capability=16, Source Carrier ID=, tg_label_flag=0 Apr 10 09:55:01.944: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:15):-1:-1:-1/vtsp_do_call_setup_ind: Calling Number=41797329888, TON=International, NPI=ISDN, Screening=Network, Presentation=Allowed CLIR=FALSE, CLID Transparent=FALSE, Null Originating Calling Number=FALSE, Calling Translated=FALSE Apr 10 09:55:01.944: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:15):-1:-1:-1/vtsp_do_call_setup_ind: Called Number=02501, TON=Subscriber, NPI=ISDN Apr 10 09:55:01.945: //-1/4EB1410181F3/VTSP:(0/1/0:15):0:-1:-1/vtsp_do_normal_call_setup_ind: Apr 10 09:55:01.945: //-1/4EB1410181F3/VTSP:(0/1/0:15):0:-1:-1/vtsp_timer: Timer Start Time=214278689, Timer Value=180000(ms) Apr 10 09:55:01.945: //-1/4EB1410181F3/VTSP:(0/1/0:15):0:-1:-1/vtsp_do_normal_call_setup_ind: IC State Machine (ICSM) Added Apr 10 09:55:01.945: //-1/4EB1410181F3/VTSP:(0/1/0:15):0:-1:-1/vtsp_insert_cdb: Apr 10 09:55:01.945: //-1/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_opened_cb: Apr 10 09:55:01.945: //-1/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] Apr 10 09:55:01.945: //-1/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/act_setup_ind_pend_success: Apr 10 09:55:01.945: //-1/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_realloc_cdb: CDB=0x7FFDB2DC8A90 Apr 10 09:55:01.945: //-1/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_indicate_call: Apr 10 09:55:01.945: //-1/4EB1410181F3/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=41797329888 cisco-anitype=1 cisco-aniplan=1 cisco-anipi=0 cisco-anisi=3 dest=02501 cisco-desttype=4 cisco-destplan=1 cisco-rdie=FFFFFFFFFFFFFFFF cisco-rdn= cisco-rdntype=-1 cisco-rdnplan=-1 cisco-rdnpi=-1 cisco-rdnsi=-1 cisco-redirectreason=-1 fwd_final_type =0 final_redirectNumber = hunt_group_timeout =0 Apr 10 09:55:01.946: //-1/4EB1410181F3/CCAPI/cc_api_call_setup_ind_common: Interface=0x7FFDA96E1740, Call Info( Calling Number=41797329888,(Calling Name=)(TON=International, NPI=ISDN, Screening=Network, Presentation=Allowed), Called Number=02501(TON=Subscriber, NPI=ISDN), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Incoming Dial-peer=10, Progress Indication=NOT END TO END ISDN(1), Calling IE Present=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1 Apr 10 09:55:01.946: //-1/4EB1410181F3/CCAPI/ccCheckClipClir: In: Calling Number=41797329888(TON=International, NPI=ISDN, Screening=Network, Presentation=Allowed) Apr 10 09:55:01.946: //-1/4EB1410181F3/CCAPI/ccCheckClipClir: Out: Calling Number=41797329888(TON=International, NPI=ISDN, Screening=Network, Presentation=Allowed) Apr 10 09:55:01.946: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Apr 10 09:55:01.946: :cc_get_feature_vsa malloc success Apr 10 09:55:01.946: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Apr 10 09:55:01.946: cc_get_feature_vsa count is 1 Apr 10 09:55:01.946: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Apr 10 09:55:01.946: :FEATURE_VSA attributes are: feature_name:0,feature_time:140727373987564,feature_id:3844 Apr 10 09:55:01.946: //577852/4EB1410181F3/CCAPI/cc_api_call_setup_ind_common: Set Up Event Sent; Call Info(Calling Number=41797329888(TON=International, NPI=ISDN, Screening=Network, Presentation=Allowed), Called Number=02501(TON=Subscriber, NPI=ISDN)) Apr 10 09:55:01.946: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_insert_cdb: Apr 10 09:55:01.946: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_timer: Timer Start Time=214278689, Timer Value=180000(ms) Apr 10 09:55:01.946: //577852/4EB1410181F3/CCAPI/cc_process_call_setup_ind: Event=0x7FFDA5254808 Apr 10 09:55:01.947: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Try with the demoted called number 02501 Apr 10 09:55:01.947: //577852/4EB1410181F3/CCAPI/ccCallSetContext: Context=0x7FFDB3011480 Apr 10 09:55:01.947: //577852/4EB1410181F3/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 577852 with tag 10 to app "_ManagedAppProcess_Default" Apr 10 09:55:01.948: //577852/4EB1410181F3/CCAPI/ccCallProceeding: Progress Indication=NULL(0) Apr 10 09:55:01.948: //577852/4EB1410181F3/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=500, Params=0x7FFDB353BDC0, Progress Indication=NOT END TO END ISDN(1) Apr 10 09:55:01.948: //577852/4EB1410181F3/CCAPI/ccCheckClipClir: In: Calling Number=+41797329888(TON=International, NPI=ISDN, Screening=Network, Presentation=Allowed) Apr 10 09:55:01.948: //577852/4EB1410181F3/CCAPI/ccCheckClipClir: Out: Calling Number=+41797329888(TON=International, NPI=ISDN, Screening=Network, Presentation=Allowed) Apr 10 09:55:01.949: //577852/4EB1410181F3/CCAPI/ccCallSetupRequest: Destination Pattern=+350200025.., Called Number=+35020002501, Digit Strip=FALSE Apr 10 09:55:01.949: //577852/4EB1410181F3/CCAPI/ccCallSetupRequest: Calling Number=+41797329888(TON=International, NPI=ISDN, Screening=Network, Presentation=Allowed), Called Number=+35020002501(TON=Subscriber, NPI=ISDN), Redirect Number=, Display Info= Account Number=, Final Destination Flag=TRUE, Guid=4EB14101-3BCB-11E8-81F3-CC167E61DF40, Outgoing Dial-peer=500 Apr 10 09:55:01.949: //577852/4EB1410181F3/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=+41797329888 cisco-anitype=1 cisco-aniplan=1 cisco-anipi=0 cisco-anisi=3 dest=+35020002501 cisco-desttype=4 cisco-destplan=1 cisco-rdie=FFFFFFFFFFFFFFFF cisco-rdn= cisco-rdntype=-1 cisco-rdnplan=-1 cisco-rdnpi=-1 cisco-rdnsi=-1 cisco-redirectreason=-1 fwd_final_type =0 final_redirectNumber = hunt_group_timeout =0 Apr 10 09:55:01.949: //577852/4EB1410181F3/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x7FFDA0F7DCC0, Interface Type=3, Destination=, Mode=0x0, Call Params(Calling Number=+41797329888,(Calling Name=)(TON=International, NPI=ISDN, Screening=Network, Presentation=Allowed), Called Number=+35020002501(TON=Subscriber, NPI=ISDN), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=500, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) Apr 10 09:55:01.949: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Apr 10 09:55:01.949: :cc_get_feature_vsa malloc success Apr 10 09:55:01.949: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Apr 10 09:55:01.949: cc_get_feature_vsa count is 2 Apr 10 09:55:01.949: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Apr 10 09:55:01.949: :FEATURE_VSA attributes are: feature_name:0,feature_time:140727373990252,feature_id:3845 Apr 10 09:55:01.949: //577853/4EB1410181F3/SIP/Error/sipSPIGetCallSessionTarget: No session target configured Apr 10 09:55:01.949: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_spi_event_handler: Event Category=1, Event=189 Apr 10 09:55:01.949: //577853/4EB1410181F3/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1 Apr 10 09:55:01.949: //577853/4EB1410181F3/CCAPI/ccCallSetContext: Context=0x7FFDB353BD40 Apr 10 09:55:01.949: //577852/4EB1410181F3/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=500 Apr 10 09:55:01.950: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_SETUP_INDICATED, event:E_CC_PROCEEDING] Apr 10 09:55:01.950: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/act_proceeding: Progress Indication=0 Apr 10 09:55:01.950: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_timer_stop: Timer Stop Time=214278689 SIP: (577853) Group (a= group line) attribute, level 65535 instance 1 not found. SIP: (577853) Group (a= group line) attribute, level 65535 instance 1 not found. Apr 10 09:55:01.951: //577853/4EB1410181F3/CCAPI/cc_api_call_proceeding: Interface=0x7FFDA0F7DCC0, Progress Indication=NULL(0) SIP: (577853) Group (a= group line) attribute, level 65535 instance 1 not found. Apr 10 09:55:01.952: //577853/4EB1410181F3/SIP/Msg/ccsipDisplayMsg: Sent: INVITE sip:+35020002501@10.1.128.135 SIP/2.0 Via: SIP/2.0/TCP 10.50.66.1:5060;branch=z9hG4bK5A60521A From: ;tag=7FB9D418-1365 To: Date: Tue, 10 Apr 2018 07:55:01 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 Supported: 100rel,timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Cisco-Guid: 1320239361-1003164136-2180238358-2120343360 User-Agent: Cisco-SIPGateway/IOS-15.4.3.S4 Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Max-Forwards: 70 Timestamp: 1523346901 Contact: Call-Info: ;method="NOTIFY;Event=telephone-event;Duration=2000" Expires: 180 Allow-Events: telephone-event Content-Type: application/sdp Content-Disposition: session;handling=required Content-Length: 361 v=0 o=CiscoSystemsSIP-GW-UserAgent 1252 1538 IN IP4 10.50.66.1 s=SIP Call c=IN IP4 10.50.66.1 t=0 0 m=audio 20394 RTP/AVP 0 8 18 100 101 c=IN IP4 10.50.66.1 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:100 X-NSE/8000 a=fmtp:100 192-194 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 Apr 10 09:55:01.952: ISDN Se0/1/0:15 Q931: TX -> CALL_PROC pd = 8 callref = 0x8001 Channel ID i = 0xA98381 Exclusive, Channel 1 Apr 10 09:55:02.007: //577853/4EB1410181F3/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 100 Trying Via: SIP/2.0/TCP 10.50.66.1:5060;branch=z9hG4bK5A60521A From: ;tag=7FB9D418-1365 To: Date: Tue, 10 Apr 2018 07:55:01 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 CSeq: 101 INVITE Allow-Events: presence Content-Length: 0 Apr 10 09:55:02.029: //577853/4EB1410181F3/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 180 Ringing Via: SIP/2.0/TCP 10.50.66.1:5060;branch=z9hG4bK5A60521A From: ;tag=7FB9D418-1365 To: ;tag=21006620~066d7f19-322a-43ba-8eca-ef58947b04ca-62878093 Date: Tue, 10 Apr 2018 07:55:01 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 CSeq: 101 INVITE Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence Server: Cisco-CUCM10.5 Call-Info: ;method="NOTIFY;Event=telephone-event;Duration=500" Call-Info: ;x-cisco-video-traffic-class=MIXED Supported: X-cisco-srtp-fallback Supported: Geolocation P-Preferred-Identity: Remote-Party-ID: ;party=called;screen=no;privacy=off Contact: Content-Length: 0 Apr 10 09:55:02.029: //577853/4EB1410181F3/CCAPI/cc_api_call_alert: Interface=0x7FFDA0F7DCC0, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) Apr 10 09:55:02.030: //577853/4EB1410181F3/CCAPI/cc_api_call_alert: Call Entry(Retry Count=0, Responsed=TRUE) Apr 10 09:55:02.030: //577852/4EB1410181F3/CCAPI/ccCallAlert: Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) Apr 10 09:55:02.030: //577852/4EB1410181F3/CCAPI/ccCallAlert: Call Entry(Responsed=TRUE, Alert Sent=TRUE) Apr 10 09:55:02.030: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_PROCEEDING, event:E_CC_ALERT] Apr 10 09:55:02.030: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/act_alert: Progress Indication=0, Signal Indication=1, Setup Progress Indication=1 Apr 10 09:55:02.030: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_timer_stop: Timer Stop Time=214278697 Apr 10 09:55:02.031: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/act_alert: [Ring Back]-Tone Played In Direction [Network] Apr 10 09:55:02.031: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_open_packet_path: Progress Indication=0 Apr 10 09:55:02.031: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/act_alert: Progress Indication=0, Tone=Ring Back Apr 10 09:55:02.033: ISDN Se0/1/0:15 Q931: TX -> ALERTING pd = 8 callref = 0x8001 Apr 10 09:55:02.075: //577853/4EB1410181F3/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.50.66.1:5060;branch=z9hG4bK5A60521A From: ;tag=7FB9D418-1365 To: ;tag=21006620~066d7f19-322a-43ba-8eca-ef58947b04ca-62878093 Date: Tue, 10 Apr 2018 07:55:01 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 CSeq: 101 INVITE Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence Supported: replaces Server: Cisco-CUCM10.5 Call-Info: ;x-cisco-video-traffic-class=MIXED Supported: X-cisco-srtp-fallback Supported: Geolocation Session-Expires: 14400;refresher=uas Require: timer P-Preferred-Identity: Remote-Party-ID: ;party=called;screen=no;privacy=off Contact: Content-Type: application/sdp Content-Length: 170 v=0 o=CiscoSystemsCCM-SIP 21006620 1 IN IP4 10.1.128.135 s=SIP Call c=IN IP4 10.1.100.45 b=TIAS:64000 b=AS:64 t=0 0 m=audio 56860 RTP/AVP 0 a=rtpmap:0 PCMU/8000 SIP: Attribute mid, level 1 instance 1 not found. Apr 10 09:55:02.076: //577853/4EB1410181F3/CCAPI/cc_api_call_connected: Interface=0x7FFDA0F7DCC0, Data Bitmask=0x1, Progress Indication=NULL(0), Connection Handle=0 Apr 10 09:55:02.076: //577853/4EB1410181F3/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) Apr 10 09:55:02.076: //577852/4EB1410181F3/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Null, Tone Direction=Network, Params=0x0, Call Id=577852 Apr 10 09:55:02.076: //577852/4EB1410181F3/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFFFFFFFFFF, callID1=0x8D13C, gcid=4EB14101-3BCB11E8-81F3CC16-7E61DF40, tag=0x0) Apr 10 09:55:02.076: //577853/4EB1410181F3/CCAPI/ccConferenceCreate: (confID=0xFFFFFFFFFFFFFFFF, callID2=0x8D13D, gcid=4EB14101-3BCB11E8-81F3CC16-7E61DF40, tag=0x0) Apr 10 09:55:02.076: //577852/4EB1410181F3/CCAPI/ccConferenceCreate: Conference Id=0xFFFFFFFFFFFFFFFF, Call Id1=577852, Call Id2=577853, Tag=0x0 Apr 10 09:55:02.076: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_bridge_check_cb: Apr 10 09:55:02.076: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_bridge_check_cb:exit@1505 Apr 10 09:55:02.076: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_bridge_status_cb: Apr 10 09:55:02.076: //577852/4EB1410181F3/CCAPI/cc_api_bridge_done: Conference Id=0x1A5, Source Interface=0x7FFDA96E1740, Source Call Id=577852, Destination Call Id=577853, Disposition=0x0, Tag=0xFFFFFFFFFFFFFFFF Apr 10 09:55:02.076: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_save_fax_config: Apr 10 09:55:02.077: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=T38_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=Dialpeer, Peer=500 Apr 10 09:55:02.077: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_save_fax_config: Fax Version:=0, LS Red=2, HS Red=0 Apr 10 09:55:02.077: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_set_fax_feat_param: Apr 10 09:55:02.077: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_save_tty_config: Apr 10 09:55:02.077: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_save_tty_config: Save TTY configuration Apr 10 09:55:02.077: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_set_tty_feat_param: Apr 10 09:55:02.077: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_set_tty_feat_param: Set TTY feat parameters Apr 10 09:55:02.077: //577853/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Apr 10 09:55:02.077: cc_api_get_xcode_stream : 4982 Apr 10 09:55:02.077: //577853/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Apr 10 09:55:02.077: cc_api_get_xcode_stream : 4982 Apr 10 09:55:02.077: //577853/4EB1410181F3/CCAPI/cc_api_bridge_done: Conference Id=0x1A5, Source Interface=0x7FFDA0F7DCC0, Source Call Id=577853, Destination Call Id=577852, Disposition=0x0, Tag=0x0 Apr 10 09:55:02.077: //577852/4EB1410181F3/CCAPI/cc_generic_bridge_done: Conference Id=0x1A5, Source Interface=0x7FFDA0F7DCC0, Source Call Id=577853, Destination Call Id=577852, Disposition=0x0, Tag=0x0 Apr 10 09:55:02.077: //577852/4EB1410181F3/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x1A5, Destination Call Id=577853) Apr 10 09:55:02.077: //577853/4EB1410181F3/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x1A5, Destination Call Id=577852) Apr 10 09:55:02.077: //577852/4EB1410181F3/CCAPI/ccConferenceCreate: Apr 10 09:55:02.077: confID:0x1A5; callEntry1 callID1:0x8D13C, type:6; callEntry2 callID2:0x8D13D, type:3 Apr 10 09:55:02.078: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_ALERTING, event:E_CC_DO_CAPS_IND] Apr 10 09:55:02.078: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_IND Apr 10 09:55:02.078: //577852/4EB1410181F3/CCAPI/cc_api_caps_ind: Destination Interface=0x7FFDA0F7DCC0, Destination Call Id=577853, Source Call Id=577852, Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1, Modem=0x2, Codec Bytes=20, Signal Type=3) Apr 10 09:55:02.078: //577852/4EB1410181F3/CCAPI/cc_api_caps_ind: Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms), Playout Max=1000(ms), Fax Nom=300(ms)) Apr 10 09:55:02.078: //577853/4EB1410181F3/CCAPI/cc_api_caps_ind: Destination Interface=0x7FFDA96E1740, Destination Call Id=577852, Source Call Id=577853, Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x1, Modem=0x0, Codec Bytes=160, Signal Type=2) Apr 10 09:55:02.078: //577853/4EB1410181F3/CCAPI/cc_api_caps_ind: Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms), Playout Max=1000(ms), Fax Nom=300(ms)) Apr 10 09:55:02.078: //577853/4EB1410181F3/CCAPI/cc_api_caps_ack: Destination Interface=0x7FFDA96E1740, Destination Call Id=577852, Source Call Id=577853, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=3991) Apr 10 09:55:02.078: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_ALERTING, event:E_CC_CAPS_IND] Apr 10 09:55:02.078: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_ACK Apr 10 09:55:02.078: //577852/4EB1410181F3/CCAPI/cc_api_caps_ack: Destination Interface=0x7FFDA0F7DCC0, Destination Call Id=577853, Source Call Id=577852, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=3991) Apr 10 09:55:02.078: //577852/4EB1410181F3/CCAPI/ccCallConnect: Progress Indication=NULL(0), Data Bitmask=0x1 Apr 10 09:55:02.078: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_call_connect: Connected Name Apr 10 09:55:02.079: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_call_connect: Connected Number +35020002501 Apr 10 09:55:02.079: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_call_connect: Connected oct3a 80 Apr 10 09:55:02.079: //577852/4EB1410181F3/CCAPI/ccCallConnect: Call Entry(Connected=TRUE, Responsed=TRUE) Apr 10 09:55:02.079: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_ALERTING, event:E_CC_CONNECT] Apr 10 09:55:02.079: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/act_alert_connect: Progress Indication=0 Apr 10 09:55:02.079: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_ring_noan_timer_stop: Timer Stop Time=214278702 Apr 10 09:55:02.081: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_feature_notify_cb: Feature ID=0, Feature Status=1 Apr 10 09:55:02.081: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_reactivate_ringback: Apr 10 09:55:02.081: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_reactivate_ringback:exit@1296 Apr 10 09:55:02.081: //577852/4EB1410181F3/CCAPI/cc_api_voice_mode_event: Call Id=577852 Apr 10 09:55:02.081: //577852/4EB1410181F3/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x7FFDB3011480) Apr 10 09:55:02.082: //577852/4EB1410181F3/CCAPI/cc_process_notify_bridge_done: Conference Id=0x1A5, Call Id1=577852, Call Id2=577853 Apr 10 09:55:02.082: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_ENABLE_TDM_RTCP Apr 10 09:55:02.082: ISDN **ERROR**: validate_connected_number: Invalid connected_number Apr 10 09:55:02.082: ISDN Se0/1/0:15 Q931: TX -> CONNECT pd = 8 callref = 0x8001 Apr 10 09:55:02.082: //577853/4EB1410181F3/SIP/Msg/ccsipDisplayMsg: Sent: ACK sip:+35020002501@10.1.128.135:5060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.50.66.1:5060;branch=z9hG4bK5A606DDA From: ;tag=7FB9D418-1365 To: ;tag=21006620~066d7f19-322a-43ba-8eca-ef58947b04ca-62878093 Date: Tue, 10 Apr 2018 07:55:01 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: telephone-event Content-Length: 0 Apr 10 09:55:02.171: ISDN Se0/1/0:15 Q931: RX <- CONNECT_ACK pd = 8 callref = 0x0001 Apr 10 09:55:05.038: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: INVITE sip:+41797329888@10.50.66.1:5060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.1.128.135:5060;branch=z9hG4bK9387f8e13c2e9 From: ;tag=21006620~066d7f19-322a-43ba-8eca-ef58947b04ca-62878093 To: ;tag=7FB9D418-1365 Date: Tue, 10 Apr 2018 07:55:04 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 Supported: timer,resource-priority,replaces Cisco-Guid: 1320239361-1003164136-2180238358-2120343360 User-Agent: Cisco-CUCM10.5 Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY CSeq: 101 INVITE Max-Forwards: 70 Expires: 180 Allow-Events: presence Call-Info: ;x-cisco-video-traffic-class=MIXED Supported: X-cisco-srtp-fallback Supported: Geolocation Session-Expires: 14400;refresher=uac Min-SE: 1800 P-Preferred-Identity: Remote-Party-ID: ;party=calling;screen=no;privacy=off Contact: Content-Type: application/sdp Content-Length: 363 v=0 o=CiscoSystemsCCM-SIP 21006620 2 IN IP4 10.1.128.135 s=SIP Call c=IN IP4 10.1.100.45 t=0 0 m=image 56860 udptl t38 a=T38FaxVersion:3 a=T38MaxBitRate:9600 a=T38FaxFillBitRemoval:0 a=T38FaxTranscodingMMR:0 a=T38FaxTranscodingJBIG:0 a=T38FaxRateManagement:transferredTCF a=T38FaxUdpEC:t38UDPRedundancy a=T38FaxMaxBuffer:200 a=T38FaxMaxDatagram:72 Apr 10 09:55:05.038: //577852/4EB1410181F3/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Null, Tone Direction=Sum Network, Params=0x0, Call Id=577852 Apr 10 09:55:05.038: //-1/xxxxxxxxxxxx/SIP/Error/httpish_msg_free: Freeing NULL pointer! SIP: Attribute mid, level 1 instance 1 not found. Apr 10 09:55:05.039: //577853/4EB1410181F3/SIP/Error/sipIPIP_add_bandwidth_line: Unknown media_type = 11 Apr 10 09:55:05.040: //577853/4EB1410181F3/CCAPI/cc_api_t38_fax_start: Destination Interface=0x7FFDA96E1740, Destination Call Id=577852, Source Call Id=577853, Caps(Codec=T38Fax(0x10000), Fax Rate=FAX_RATE_9600(0x20),Fax Version:=0, Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=20, Signal Type=2) Apr 10 09:55:05.040: //577853/4EB1410181F3/SIP/Error/sipIPIP_add_bandwidth_line: Unknown media_type = 11 Apr 10 09:55:05.040: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_CONNECT, event:E_CC_T38_START] SIP: (577853) Group (a= group line) attribute, level 65535 instance 1 not found. Apr 10 09:55:05.042: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_MC_LOCAL_DNLD_DONE Apr 10 09:55:05.042: //577852/xxxxxxxxxxxx/CCAPI/cc_api_local_codec_dnld_done: (dstVdbPtr=0x7FFDA0F7DCC0, dstCallId=0x8D13D, srcCallId=0x8D13C) Apr 10 09:55:05.042: //577852/4EB1410181F3/CCAPI/cc_api_local_codec_dnld_done: (dstVdbPtr=0x7FFDA0F7DCC0, dstCallId=0x8D13D, srcCallId=0x8D13C) Apr 10 09:55:05.042: //577853/4EB1410181F3/CCAPI/cc_api_local_codec_dnld_done: Destination Interface=0x7FFDA0F7DCC0, Destination Call Id=577853, Source Call Id=577852 Apr 10 09:55:05.043: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_SET_FAX_MODE Apr 10 09:55:05.043: //577853/4EB1410181F3/CCAPI/cc_api_set_fax_mode: Destination Interface=0x7FFDA0F7DCC0, Destination Call Id=577853, Source Call Id=577852 Apr 10 09:55:05.043: //577853/4EB1410181F3/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 100 Trying Via: SIP/2.0/TCP 10.1.128.135:5060;branch=z9hG4bK9387f8e13c2e9 From: ;tag=21006620~066d7f19-322a-43ba-8eca-ef58947b04ca-62878093 To: ;tag=7FB9D418-1365 Date: Tue, 10 Apr 2018 07:55:05 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 CSeq: 101 INVITE Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-15.4.3.S4 Content-Length: 0 Apr 10 09:55:05.043: //577853/4EB1410181F3/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.1.128.135:5060;branch=z9hG4bK9387f8e13c2e9 From: ;tag=21006620~066d7f19-322a-43ba-8eca-ef58947b04ca-62878093 To: ;tag=7FB9D418-1365 Date: Tue, 10 Apr 2018 07:55:05 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 CSeq: 101 INVITE Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Allow-Events: telephone-event Contact: Supported: replaces Supported: sdp-anat Server: Cisco-SIPGateway/IOS-15.4.3.S4 Require: timer Session-Expires: 14400;refresher=uac Supported: timer Content-Type: application/sdp Content-Length: 311 v=0 o=CiscoSystemsSIP-GW-UserAgent 1252 1538 IN IP4 10.50.66.1 s=SIP Call c=IN IP4 10.50.66.1 t=0 0 m=image 20394 udptl t38 c=IN IP4 10.50.66.1 a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:200 a=T38FaxMaxDatagram:320 a=T38FaxUdpEC:t38UDPRedundancy Apr 10 09:55:05.099: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: ACK sip:+41797329888@10.50.66.1:5060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.1.128.135:5060;branch=z9hG4bK9387f98335df5 From: ;tag=21006620~066d7f19-322a-43ba-8eca-ef58947b04ca-62878093 To: ;tag=7FB9D418-1365 Date: Tue, 10 Apr 2018 07:55:04 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 User-Agent: Cisco-CUCM10.5 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: presence Content-Length: 0 Apr 10 09:55:05.099: //577853/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: Apr 10 09:55:05.099: call_info mainst_callID:0x8D13D, peer_callID:0x8D13C, confID:0x1A5, spi_type:4, media_flo_thru:1,num_streams:1, swmtpmsp_present:0 Apr 10 09:55:05.099: //577853/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify: Apr 10 09:55:05.099: stream[0]: callid:0x8D13D, media_passthrough:1 Apr 10 09:55:05.099: //577852/4EB1410181F3/CCAPI/cc_api_remote_codec_dnld_done: Destination Interface=0x7FFDA96E1740, Destination Call Id=577852, Source Call Id=577853, Xmit Function=0x7FFE0BBDC3D0 Apr 10 09:55:05.099: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_CONNECT, event:E_CC_T38_REMOTE_DNLD_DONE] Apr 10 09:55:14.834: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: OPTIONS sip:10.50.66.1:5060 SIP/2.0 Via: SIP/2.0/TCP 10.50.66.1:5060;branch=z9hG4bK5A60A1C31 From: ;tag=7FBA066A-19E To: Date: Tue, 10 Apr 2018 07:55:14 GMT Call-ID: 565FF616-3BCB11E8-A98CAD25-DD7BDD2E@10.50.66.1 User-Agent: Cisco-SIPGateway/IOS-15.4.3.S4 Max-Forwards: 70 CSeq: 101 OPTIONS Contact: Content-Length: 0 Apr 10 09:55:14.835: //-1/xxxxxxxxxxxx/SIP/Error/httpish_msg_free: Freeing NULL pointer! Apr 10 09:55:14.835: //577858/5660442AA98D/SIP/Error/sipSPIGetPeerByCalledPartyId: input arg error Apr 10 09:55:14.835: //577858/5660442AA98D/SIP/Error/sipSPIUpdateCallInfo: input argument error Apr 10 09:55:14.835: //577858/5660442AA98D/SIP/Error/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer is absent.. Apr 10 09:55:14.835: //577858/5660442AA98D/SIP/Error/ccsip_ipip_media_forking_intra_frame_request_config: MF:video profile Dial-peer is absent.. Apr 10 09:55:14.837: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.50.66.1:5060;branch=z9hG4bK5A60A1C31 From: ;tag=7FBA066A-19E To: ;tag=7FBA066C-480 Date: Tue, 10 Apr 2018 07:55:14 GMT Call-ID: 565FF616-3BCB11E8-A98CAD25-DD7BDD2E@10.50.66.1 Server: Cisco-SIPGateway/IOS-15.4.3.S4 CSeq: 101 OPTIONS Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Allow-Events: telephone-event Accept: application/sdp Supported: 100rel,timer,resource-priority,replaces,sdp-anat Content-Type: application/sdp Content-Length: 365 v=0 o=CiscoSystemsSIP-GW-UserAgent 8552 9182 IN IP4 10.50.66.1 s=SIP Call c=IN IP4 10.50.66.1 t=0 0 m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3 c=IN IP4 10.50.66.1 m=image 0 udptl t38 c=IN IP4 10.50.66.1 a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:200 a=T38FaxMaxDatagram:320 a=T38FaxUdpEC:t38UDPRedundancy Apr 10 09:55:21.295: ISDN Se0/1/0:15 Q931: RX <- DISCONNECT pd = 8 callref = 0x0001 Cause i = 0x8A90 - Normal call clearing Progress Ind i = 0x8288 - In-band info or appropriate now available Apr 10 09:55:21.295: ISDN Se0/1/0:15 Q931: call_disc: PI received in disconnect; Postpone sending RELEASE for callid 0x225 Apr 10 09:55:21.295: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_CONNECT, event:E_TSP_PROCEEDING] Apr 10 09:55:21.295: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: Unexpected EVENT [E_TSP_PROCEEDING] Received For STATE [S_CONNECT]; Previous STATE [0.10 ] Apr 10 09:55:21.295: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_CONNECT, event:E_TSP_DISCONNECT_PI_IND] Apr 10 09:55:21.295: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/act_setup_pend_disc_pi_ind: Progress Indication=8, Cause Value=16, Convert To Disconnect Without PI=FALSE Apr 10 09:55:21.296: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_ring_noan_timer_stop: Timer Stop Time=214280624 Apr 10 09:55:21.296: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_set_release_source: Release Direction=PSTN, Release Source=Calling Party-PSTN Apr 10 09:55:21.296: //577852/4EB1410181F3/CCAPI/cc_api_call_disc_prog_ind: Cause Value=16, Progress Indication=INBAND(8), Interface=0x7FFDA96E1740 Apr 10 09:55:21.296: //577852/4EB1410181F3/CCAPI/cc_api_call_disc_prog_ind: Event(Progress Indication=0x0), Call Entry(Responsed=TRUE) Apr 10 09:55:21.296: //577852/4EB1410181F3/CCAPI/ccConferenceDestroy: Conference Id=0x1A5, Tag=0x0 Apr 10 09:55:21.296: //577852/4EB1410181F3/CCAPI/ccConferenceDestroy: Apr 10 09:55:21.296: confID:0x1A5; callEntry1 callID1:0x8D13C, type:6; callEntry2 callID2:0x8D13D, type:3 Apr 10 09:55:21.296: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_fpi_event_cb: Event=E_DSMP_FPI_DISABLE_TDM_RTCP Apr 10 09:55:21.296: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_bridge_status_cb: Apr 10 09:55:21.296: //577852/4EB1410181F3/CCAPI/cc_api_bridge_drop_done: Conference Id=0x1A5, Source Interface=0x7FFDA96E1740, Source Call Id=577852, Destination Call Id=577853, Disposition=0x0, Tag=0x0 Apr 10 09:55:21.297: //577853/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Apr 10 09:55:21.297: cc_api_get_xcode_stream : 4982 Apr 10 09:55:21.297: //577852/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Apr 10 09:55:21.297: cc_api_get_xcode_stream : 4982 Apr 10 09:55:21.297: //577853/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Apr 10 09:55:21.297: cc_api_get_xcode_stream : 4982 Apr 10 09:55:21.297: //577853/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Apr 10 09:55:21.297: cc_api_get_xcode_stream : 4982 Apr 10 09:55:21.297: //577853/4EB1410181F3/CCAPI/cc_api_bridge_drop_done: Conference Id=0x1A5, Source Interface=0x7FFDA0F7DCC0, Source Call Id=577853, Destination Call Id=577852, Disposition=0x0, Tag=0x0 Apr 10 09:55:21.297: //577852/4EB1410181F3/CCAPI/cc_generic_bridge_done: Conference Id=0x1A5, Source Interface=0x7FFDA0F7DCC0, Source Call Id=577853, Destination Call Id=577852, Disposition=0x0, Tag=0x0 Apr 10 09:55:21.298: //577852/4EB1410181F3/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16) Apr 10 09:55:21.299: //577852/4EB1410181F3/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) Apr 10 09:55:21.299: //577852/4EB1410181F3/CCAPI/cc_api_get_transfer_info: Transfer Number=NULL Apr 10 09:55:21.299: //577853/4EB1410181F3/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) Apr 10 09:55:21.299: //577853/4EB1410181F3/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) Apr 10 09:55:21.299: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_CONNECT, event:E_CC_DISCONNECT] Apr 10 09:55:21.299: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/act_disconnect: Cause Value=16 Apr 10 09:55:21.299: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_ring_noan_timer_stop: Timer Stop Time=214280624 Apr 10 09:55:21.299: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_pcm_cap_conf_tone_timer_stop: Timer Stop Time=214280624 Apr 10 09:55:21.299: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_save_fax_config: Apr 10 09:55:21.299: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=T38_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED Fax Parameters Set By=Dialpeer, Peer=500 Apr 10 09:55:21.299: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_save_fax_config: Fax Version:=0, LS Red=2, HS Red=0 Apr 10 09:55:21.299: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_set_fax_feat_param: Apr 10 09:55:21.315: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_dsm_get_levels_done_cb: Apr 10 09:55:21.315: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE] Apr 10 09:55:21.315: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/act_dsm_dsp_stats_complete: Apr 10 09:55:21.315: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_timer: Timer Start Time=214280626, Timer Value=60000(ms) Apr 10 09:55:21.315: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_WAIT_RELEASE, event:E_VTSP_DSM_STATS_COMPLETE] Apr 10 09:55:21.316: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: Unexpected EVENT [E_VTSP_DSM_STATS_COMPLETE] Received For STATE [S_WAIT_RELEASE]; Previous STATE [0.16 ] Apr 10 09:55:21.316: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_process_event: [state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF] Apr 10 09:55:21.316: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/act_wrelease_release: Apr 10 09:55:21.316: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_play_busy_timer_stop: Timer Stop Time=214280626 Apr 10 09:55:21.316: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_timer_stop: Timer Stop Time=214280626 Apr 10 09:55:21.316: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_do_call_history: Apr 10 09:55:21.316: //577852/4EB1410181F3/CCAPI/cc_api_call_disc_cause_update: Cause Value=16 Apr 10 09:55:21.316: //577852/4EB1410181F3/CCAPI/cc_api_call_disc_cause_update: Call Entry(Disconnect Cause=16) Apr 10 09:55:21.316: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_do_call_history: Coder Rate=57 Apr 10 09:55:21.316: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:1:1/vtsp_is_valid_dsm_handle: DMGR=0x7FFDB2D20F70, VTSP CDB=0x7FFDB2DC8A90 Apr 10 09:55:21.317: //577853/4EB1410181F3/SIP/Msg/ccsipDisplayMsg: Sent: BYE sip:+35020002501@10.1.128.135:5060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.50.66.1:5060;branch=z9hG4bK5A60B1269 From: ;tag=7FB9D418-1365 To: ;tag=21006620~066d7f19-322a-43ba-8eca-ef58947b04ca-62878093 Date: Tue, 10 Apr 2018 07:55:05 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 User-Agent: Cisco-SIPGateway/IOS-15.4.3.S4 Max-Forwards: 70 Timestamp: 1523346921 CSeq: 102 BYE Reason: Q.850;cause=16 Content-Length: 0 Apr 10 09:55:21.317: ISDN Se0/1/0:15 Q931: TX -> RELEASE pd = 8 callref = 0x8001 Apr 10 09:55:21.318: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:-1:-1/vtsp_dsm_closed_cb: Apr 10 09:55:21.318: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] Apr 10 09:55:21.318: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:-1:-1/act_terminate: Apr 10 09:55:21.318: //577852/4EB1410181F3/VTSP:(0/1/0:15):0:-1:-1/vtsp_timer_stop: Timer Stop Time=214280626 Apr 10 09:55:21.318: //577852/4EB1410181F3/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x7FFDA96E1740, Tag=0x0, Call Id=577852, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) Apr 10 09:55:21.318: //577852/4EB1410181F3/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent Apr 10 09:55:21.319: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: Apr 10 09:55:21.319: :cc_free_feature_vsa freeing 7FFDA522FEE0 Apr 10 09:55:21.319: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: Apr 10 09:55:21.319: vsacount in free is 1 Apr 10 09:55:21.319: //-1/4EB1410181F3/VTSP:(0/1/0:15):0:-1:-1/vtsp_free_cdb: CDB=0x7FFDB2DC8A90 Apr 10 09:55:21.371: //577853/4EB1410181F3/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.50.66.1:5060;branch=z9hG4bK5A60B1269 From: ;tag=7FB9D418-1365 To: ;tag=21006620~066d7f19-322a-43ba-8eca-ef58947b04ca-62878093 Date: Tue, 10 Apr 2018 07:55:21 GMT Call-ID: 4EB25293-3BCB11E8-A988AD25-DD7BDD2E@10.50.66.1 Server: Cisco-CUCM10.5 CSeq: 102 BYE Content-Length: 0 Apr 10 09:55:21.372: //577853/4EB1410181F3/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x7FFDA0F7DCC0, Tag=0x0, Call Id=577853, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) Apr 10 09:55:21.372: //577853/4EB1410181F3/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent Apr 10 09:55:21.372: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: Apr 10 09:55:21.372: :cc_free_feature_vsa freeing 7FFDA5230960 Apr 10 09:55:21.372: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: Apr 10 09:55:21.372: vsacount in free is 0 Apr 10 09:55:21.394: ISDN Se0/1/0:15 Q931: RX <- RELEASE_COMP pd = 8 callref = 0x0001ter no mon