HQ-COE-VG1-NET-CBT09#sh loggi Syslog logging: enabled (0 messages dropped, 3 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, 1125 messages logged, xml disabled, filtering disabled Exception Logging: size (8192 bytes) Count and timestamp logging messages: disabled Persistent logging: disabled No active filter modules. Trap logging: level informational, 78 message lines logged Logging Source-Interface: VRF Name: Log Buffer (4000000 bytes): 000513: Jul 27 16:37:55.200: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: INVITE sip:94646677@172.20.201.18:5060 SIP/2.0 Via: SIP/2.0/TCP 10.0.32.220:5060;branch=z9hG4bKe5d1b4f4e3b From: "reda tvtc" ;tag=80917~9e377a90-83b8-d4cb-5cb6-d3a322e725a2-22360306 To: Date: Mon, 27 Jul 2015 13:37:55 GMT Call-ID: ae414100-5b613433-775-dc20000a@10.0.32.220 Supported: timer,resource-priority,replaces Min-SE: 1800 User-Agent: Cisco-CUCM10.5 Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY CSeq: 101 INVITE Expires: 180 Allow-Events: presence Supported: X-cisco-srtp-fallback,X-cisco-original-called Cisco-Guid: 2923512064-0000065536-0000001820-3693084682 Session-Expires: 1800 P-Asserted-Identity: "reda tvtc" Remote-Party-ID: "reda tvtc" ;party=calling;screen=yes;privacy=off Contact: Max-Forwards: 70 Content-Type: application/sdp Content-Length: 199 v=0 o=CiscoSystemsCCM-SIP 80917 1 IN IP4 10.0.32.220 s=SIP Call c=IN IP4 10.0.32.220 t=0 0 m=audio 26106 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 000514: Jul 27 16:37:55.200: //-1/AE4141000000/SIP/State/sipSPIChangeState: 0x30E10E8 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 000515: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING) 000516: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_IDLE, event:E_SIP_INVITE_SDP_RCVD 000517: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_RCVD_SDP 000518: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT 000519: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_RCVD 000520: Jul 27 16:37:55.204: //-1/AE4141000000/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username=4552 ----- ccCallInfo IE subfields ----- cisco-ani=4552 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=1 dest=94646677 cisco-desttype=0 cisco-destplan=0 cisco-rdie=FFFFFFFF cisco-rdn= cisco-rdntype=0 cisco-rdnplan=0 cisco-rdnpi=-1 cisco-rdnsi=-1 cisco-redirectreason=-1 fwd_final_type =0 final_redirectNumber = hunt_group_timeout =0 000521: Jul 27 16:37:55.204: //-1/AE4141000000/CCAPI/cc_api_call_setup_ind_common: Interface=0x14425F68, Call Info( Calling Number=4552,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed), Called Number=94646677(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Incoming Dial-peer=200, Progress Indication=NULL(0), Calling IE Present=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=155969 000522: Jul 27 16:37:55.204: //-1/AE4141000000/CCAPI/ccCheckClipClir: In: Calling Number=4552(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed) 000523: Jul 27 16:37:55.204: //-1/AE4141000000/CCAPI/ccCheckClipClir: Out: Calling Number=4552(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed) 000524: Jul 27 16:37:55.204: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 000525: Jul 27 16:37:55.204: :cc_get_feature_vsa malloc success 000526: Jul 27 16:37:55.204: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 000527: Jul 27 16:37:55.204: cc_get_feature_vsa count is 1 000528: Jul 27 16:37:55.204: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 000529: Jul 27 16:37:55.204: :FEATURE_VSA attributes are: feature_name:0,feature_time:63432472,feature_id:6784 000530: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/cc_api_call_setup_ind_common: Set Up Event Sent; Call Info(Calling Number=4552(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed), Called Number=94646677(TON=Unknown, NPI=Unknown)) 000531: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/sipSPIChangeState: 0x30E10E8 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_RECD_INVITE, SUBSTATE_NONE) 000532: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/cc_process_call_setup_ind: Event=0x2EE4E78 000533: Jul 27 16:37:55.204: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Try with the demoted called number 94646677 000534: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/ccCallSetContext: Context=0x139540B4 000535: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 155969 with tag 200 to app "_ManagedAppProcess_Default" 000536: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/ccCallProceeding: Progress Indication=NULL(0) 000537: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=140, Params=0x139621D4, Progress Indication=NULL(0) 000538: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/ccCheckClipClir: In: Calling Number=2504552(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed) 000539: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/ccCheckClipClir: Out: Calling Number=2504552(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed) 000540: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/ccCallSetupRequest: Destination Pattern=9[1245678]......, Called Number=4646677, Digit Strip=FALSE 000541: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/ccCallSetupRequest: Calling Number=2504552(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed), Called Number=4646677(TON=Unknown, NPI=Unknown), Redirect Number=, Display Info=reda tvtc Account Number=4552, Final Destination Flag=TRUE, Guid=AE414100-0001-0000-0000-071CDC20000A, Outgoing Dial-peer=140 000542: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username=4552 ----- ccCallInfo IE subfields ----- cisco-ani=2504552 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=1 dest=4646677 cisco-desttype=0 cisco-destplan=0 cisco-rdie=FFFFFFFF cisco-rdn= cisco-rdntype=0 cisco-rdnplan=0 cisco-rdnpi=-1 cisco-rdnsi=-1 cisco-redirectreason=-1 fwd_final_type =0 final_redirectNumber = hunt_group_timeout =0 000543: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x14425F68, Interface Type=3, Destination=, Mode=0x0, Call Params(Calling Number=2504552,(Calling Name=reda tvtc)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed), Called Number=4646677(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=140, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) 000544: Jul 27 16:37:55.204: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 000545: Jul 27 16:37:55.204: :cc_get_feature_vsa malloc success 000546: Jul 27 16:37:55.204: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 000547: Jul 27 16:37:55.204: cc_get_feature_vsa count is 2 000548: Jul 27 16:37:55.204: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 000549: Jul 27 16:37:55.204: :FEATURE_VSA attributes are: feature_name:0,feature_time:63432920,feature_id:6785 000550: Jul 27 16:37:55.204: //155970/000000000000/SIP/State/sipSPIChangeState: 0x30DB150 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 000551: Jul 27 16:37:55.204: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_MODE 000552: Jul 27 16:37:55.204: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container 000553: Jul 27 16:37:55.204: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sip_early_dialog_container 000554: Jul 27 16:37:55.204: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE 000555: Jul 27 16:37:55.204: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_FLOW_MODE 000556: Jul 27 16:37:55.204: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container 000557: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT, event:E_SIP_IWF_EV_SET_MODE 000558: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container 000559: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sip_early_dialog_container 000560: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE 000561: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT, event:E_SIP_IWF_EV_SET_FLOW_MODE 000562: Jul 27 16:37:55.204: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container 000563: Jul 27 16:37:55.204: //155970/AE4141000000/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1 000564: Jul 27 16:37:55.204: //155970/AE4141000000/CCAPI/ccCallSetContext: Context=0x13962184 000565: Jul 27 16:37:55.204: //155969/AE4141000000/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=140 000566: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_INIT_CALL_SETUP 000567: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_PEER_CHNL_IND 000568: Jul 27 16:37:55.208: //155970/AE4141000000/CCAPI/ccGetMediaClassTag: media class tag 0 000569: Jul 27 16:37:55.208: //155970/AE4141000000/CCAPI/ccSetMediaclassIp2ipTags: media class tags set: NR 0, ASP 0 000570: Jul 27 16:37:55.208: //155969/AE4141000000/CCAPI/ccGetMediaClassTag: media class tag 0 000571: Jul 27 16:37:55.208: //155969/AE4141000000/CCAPI/ccSetMediaclassIp2ipTags: media class tags set: NR 0, ASP 0 000572: Jul 27 16:37:55.208: //155970/AE4141000000/CCAPI/ccGet_xc_nr_asp_info: media class tags: NR 0, ASP 0 000573: Jul 27 16:37:55.208: //155969/AE4141000000/CCAPI/ccGet_xc_nr_asp_info: media class tags: NR 0, ASP 0 000574: Jul 27 16:37:55.208: //155970/AE4141000000/CCAPI/cc_api_event_indication: Event=188, Call Id=155970 000575: Jul 27 16:37:55.208: //155970/AE4141000000/CCAPI/cc_api_event_indication: Event Is Sent To Conferenced SPI(s) Directly 000576: Jul 27 16:37:55.208: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_XCODER_RESET_STREAM 000577: Jul 27 16:37:55.208: //155969/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 000578: Jul 27 16:37:55.208: cc_api_get_xcode_stream : 4819 000579: Jul 27 16:37:55.208: //155970/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 000580: Jul 27 16:37:55.208: cc_api_get_xcode_stream : 4819 000581: Jul 27 16:37:55.208: //155969/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 000582: Jul 27 16:37:55.208: cc_api_get_xcode_stream : 4819 000583: Jul 27 16:37:55.208: //155969/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 000584: Jul 27 16:37:55.208: cc_api_get_xcode_stream : 4819 000585: Jul 27 16:37:55.208: //155969/AE4141000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE 000586: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_INIT_XCODER_RESERVED 000587: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE 000588: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE 000589: Jul 27 16:37:55.208: //155970/AE4141000000/CCAPI/cc_api_call_proceeding: Interface=0x14425F68, Progress Indication=NULL(0) 000590: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_IDLE, event:E_SIP_INVITE_SDP_SENT 000591: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SENT_SDP 000592: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP 000593: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT 000594: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/sipSPIChangeState: 0x30DB150 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE) 000595: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/sipSPIChangeStreamState: Stream (callid = 155970) State changed from (STREAM_ADDING) to (STREAM_ACTIVE) 000596: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_XCODER_RESET_STREAM 000597: Jul 27 16:37:55.208: //155970/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 000598: Jul 27 16:37:55.208: cc_api_get_xcode_stream : 4819 000599: Jul 27 16:37:55.208: //155969/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 000600: Jul 27 16:37:55.208: cc_api_get_xcode_stream : 4819 000601: Jul 27 16:37:55.208: //155970/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 000602: Jul 27 16:37:55.208: cc_api_get_xcode_stream : 4819 000603: Jul 27 16:37:55.208: //155970/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 000604: Jul 27 16:37:55.208: cc_api_get_xcode_stream : 4819 000605: Jul 27 16:37:55.208: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE 000606: Jul 27 16:37:55.208: //155970/000000000000/SIP/Msg/ccsipDisplayMsg: Sent: INVITE sip:4646677@10.200.7.157:5060 SIP/2.0 Via: SIP/2.0/UDP 10.68.12.194:5060;branch=z9hG4bK1B7BA04 Remote-Party-ID: "reda tvtc" ;party=calling;screen=yes;privacy=off From: "reda tvtc" ;tag=B2522D20-598 To: Date: Mon, 27 Jul 2015 13:37:55 GMT Call-ID: 852F5C25-339B11E5-9868D278-86C302A3@10.68.12.194 Supported: timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Cisco-Guid: 2923512064-0000065536-0000001820-3693084682 User-Agent: Cisco-SIPGateway/IOS-15.2.4.M6a Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Timestamp: 1438004275 Contact: Expires: 180 Allow-Events: telephone-event Max-Forwards: 69 Session-Expires: 1800 Content-Type: application/sdp Content-Disposition: session;handling=required Content-Length: 271 v=0 o=CiscoSystemsSIP-GW-UserAgent 9265 8804 IN IP4 10.68.12.194 s=SIP Call c=IN IP4 10.68.12.194 t=0 0 m=audio 30138 RTP/AVP 0 101 19 c=IN IP4 10.68.12.194 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtpmap:19 CN/8000 a=ptime:20 000607: Jul 27 16:37:55.208: //155969/AE4141000000/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 100 Trying Via: SIP/2.0/TCP 10.0.32.220:5060;branch=z9hG4bKe5d1b4f4e3b From: "reda tvtc" ;tag=80917~9e377a90-83b8-d4cb-5cb6-d3a322e725a2-22360306 To: Date: Mon, 27 Jul 2015 13:37:55 GMT Call-ID: ae414100-5b613433-775-dc20000a@10.0.32.220 CSeq: 101 INVITE Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-15.2.4.M6a Content-Length: 0 000608: Jul 27 16:37:55.224: //155970/000000000000/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.68.12.194:5060;branch=z9hG4bK1B7BA04 Call-ID: 852F5C25-339B11E5-9868D278-86C302A3@10.68.12.194 From: "reda tvtc";tag=B2522D20-598 To: CSeq: 101 INVITE Content-Length: 0 000609: Jul 27 16:37:55.224: //155970/000000000000/SIP/State/sipSPIChangeState: 0x30DB150 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) 000610: Jul 27 16:37:55.312: //155970/000000000000/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 10.68.12.194:5060;branch=z9hG4bK1B7BA04 Record-Route: Call-ID: 852F5C25-339B11E5-9868D278-86C302A3@10.68.12.194 From: "reda tvtc";tag=B2522D20-598 To: ;tag=sbc0802o4fokucf CSeq: 101 INVITE Reason: Q.850;cause=57;text="bearer capability not authorized" Warning: 399 - "SoftX3000 R601-CCU Rel POS:[3103] Release from CR" Content-Length: 0 000611: Jul 27 16:37:55.312: //155970/000000000000/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_SENT, event:E_SIP_DIALOG_ESTD 000612: Jul 27 16:37:55.312: //155970/AE4141000000/CCAPI/cc_api_call_disconnected: Cause Value=57, Interface=0x14425F68, Call Id=155970 000613: Jul 27 16:37:55.312: //155970/AE4141000000/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=57, Retry Count=0) 000614: Jul 27 16:37:55.312: //155970/000000000000/SIP/State/sipSPIChangeState: 0x30DB150 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_DISCONNECTING, SUBSTATE_NONE) 000615: Jul 27 16:37:55.312: //155969/AE4141000000/CCAPI/ccCallReleaseResources: release reserved xcoding resource. 000616: Jul 27 16:37:55.312: //155970/AE4141000000/CCAPI/ccCallSetAAA_Accounting: Accounting=0, Call Id=155970 000617: Jul 27 16:37:55.312: //155970/AE4141000000/CCAPI/ccCallDisconnect: Cause Value=57, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=57) 000618: Jul 27 16:37:55.312: //155970/AE4141000000/CCAPI/ccCallDisconnect: Cause Value=57, Call Entry(Responsed=TRUE, Cause Value=57) 000619: Jul 27 16:37:55.312: //155970/AE4141000000/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x14425F68, Tag=0x0, Call Id=155970, Call Entry(Disconnect Cause=57, Voice Class Cause Code=0, Retry Count=0) 000620: Jul 27 16:37:55.312: //155970/AE4141000000/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 000621: Jul 27 16:37:55.312: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 000622: Jul 27 16:37:55.312: :cc_free_feature_vsa freeing 3C7E8D0 000623: Jul 27 16:37:55.312: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 000624: Jul 27 16:37:55.312: vsacount in free is 1 000625: Jul 27 16:37:55.312: //155970/000000000000/SIP/State/sipSPIChangeState: 0x30DB150 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE) 000626: Jul 27 16:37:55.312: //155970/000000000000/SIP/Call/sipSPICallInfo: The Call Setup Information is: Call Control Block (CCB) : 0x0x30DB150 State of The Call : STATE_DEAD TCP Sockets Used : NO Calling Number : 2504552 Called Number : 4646677 Source IP Address (Sig ): 10.68.12.194 Destn SIP Req Addr:Port : 10.200.7.157:5060 Destn SIP Resp Addr:Port : 10.200.7.157:5060 Destination Name : 10.200.7.157 000627: Jul 27 16:37:55.312: //155970/000000000000/SIP/Call/sipSPIMediaCallInfo: Number of Media Streams: 1 Media Stream : 1 Negotiated Codec : No Codec Negotiated Codec Bytes : 0 Nego. Codec payload : 255 (tx), 255 (rx) Negotiated Dtmf-relay : 0 Dtmf-relay Payload : 0 (tx), 0 (rx) Source IP Address (Media): 10.68.12.194 Source IP Port (Media): 30138 Destn IP Address (Media): - Destn IP Port (Media): 0 Orig Destn IP Address:Port (Media): [ - ]:0 000628: Jul 27 16:37:55.312: //155970/000000000000/SIP/Call/sipSPICallInfo: Disconnect Cause (CC) : 57 Disconnect Cause (SIP) : 403 000629: Jul 27 16:37:55.312: //155969/AE4141000000/CCAPI/ccCallDisconnect: Cause Value=57, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 000630: Jul 27 16:37:55.312: //155969/AE4141000000/CCAPI/ccCallDisconnect: Cause Value=57, Call Entry(Responsed=TRUE, Cause Value=57) 000631: Jul 27 16:37:55.312: //155969/AE4141000000/SIP/State/sipSPIChangeState: 0x30E10E8 : State change from (STATE_RECD_INVITE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE) 000632: Jul 27 16:37:55.312: //155969/AE4141000000/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 403 Forbidden Via: SIP/2.0/TCP 10.0.32.220:5060;branch=z9hG4bKe5d1b4f4e3b From: "reda tvtc" ;tag=80917~9e377a90-83b8-d4cb-5cb6-d3a322e725a2-22360306 To: ;tag=B2522D88-246D Date: Mon, 27 Jul 2015 13:37:55 GMT Call-ID: ae414100-5b613433-775-dc20000a@10.0.32.220 CSeq: 101 INVITE Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-15.2.4.M6a Reason: Q.850;cause=57 Content-Length: 0 000633: Jul 27 16:37:55.312: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: ACK sip:4646677@10.200.7.157:5060 SIP/2.0 Via: SIP/2.0/UDP 10.68.12.194:5060;branch=z9hG4bK1B7BA04 From: "reda tvtc" ;tag=B2522D20-598 To: ;tag=sbc0802o4fokucf Date: Mon, 27 Jul 2015 13:37:55 GMT Call-ID: 852F5C25-339B11E5-9868D278-86C302A3@10.68.12.194 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: telephone-event Content-Length: 0 000634: Jul 27 16:37:55.320: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: ACK sip:94646677@172.20.201.18:5060 SIP/2.0 Via: SIP/2.0/TCP 10.0.32.220:5060;branch=z9hG4bKe5d1b4f4e3b From: "reda tvtc" ;tag=80917~9e377a90-83b8-d4cb-5cb6-d3a322e725a2-22360306 To: ;tag=B2522D88-246D Date: Mon, 27 Jul 2015 13:37:55 GMT Call-ID: ae414100-5b613433-775-dc20000a@10.0.32.220 User-Agent: Cisco-CUCM10.5 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: presence Content-Length: 0 000635: Jul 27 16:37:55.320: //155969/AE4141000000/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x14425F68, Tag=0x0, Call Id=155969, Call Entry(Disconnect Cause=57, Voice Class Cause Code=0, Retry Count=0) 000636: Jul 27 16:37:55.320: //155969/AE4141000000/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 000637: Jul 27 16:37:55.320: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 000638: Jul 27 16:37:55.320: :cc_free_feature_vsa freeing 3C7E710 000639: Jul 27 16:37:55.320: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 000640: Jul 27 16:37:55.320: vsacount in free is 0 000641: Jul 27 16:37:55.320: //155969/AE4141000000/SIP/State/sipSPIChangeState: 0x30E10E8 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE) 000642: Jul 27 16:37:55.320: //155969/AE4141000000/SIP/Call/sipSPICallInfo: The Call Setup Information is: Call Control Block (CCB) : 0x0x30E10E8 State of The Call : STATE_DEAD TCP Sockets Used : YES Calling Number : 4552 Called Number : 94646677 Source IP Address (Sig ): 172.20.201.18 Destn SIP Req Addr:Port : 10.0.32.220:5060 Destn SIP Resp Addr:Port : 10.0.32.220:54271 Destination Name : 10.0.32.220 000643: Jul 27 16:37:55.320: //155969/AE4141000000/SIP/Call/sipSPIMediaCallInfo: Number of Media Streams: 1 Media Stream : 1 Negotiated Codec : g711ulaw Negotiated Codec Bytes : 160 Nego. Codec payload : 0 (tx), 0 (rx) Negotiated Dtmf-relay : 6 Dtmf-relay Payload : 101 (tx), 101 (rx) Source IP Address (Media): 172.20.201.18 Source IP Port (Media): 30136 Destn IP Address (Media): 10.0.32.220 Destn IP Port (Media): 26106 Orig Destn IP Address:Port (Media): [ - ]:0 000644: Jul 27 16:37:55.320: //155969/AE4141000000/SIP/Call/sipSPICallInfo: Disconnect Cause (CC) : 57 Disconnect Cause (SIP) : 403 000645: Jul 27 16:38:06.593: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: OPTIONS sip:10.68.12.194:5060 SIP/2.0 Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bK7sbeotau2tahtkk4sfcobdod2T39222 Call-ID: isbcf4htkpkdudeuku4cacasoaoeabuk77kh@SoftX3000 From: ;tag=sbc0802kbctotak To: CSeq: 1 OPTIONS Max-Forwards: 70 Content-Length: 0 000646: Jul 27 16:38:06.593: //-1/8BF86E8D9869/SIP/State/sipSPIChangeState: 0x30E7080 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 000647: Jul 27 16:38:06.593: //155971/8BF86E8D9869/SIP/State/sipSPIChangeState: 0x30E7080 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE) 000648: Jul 27 16:38:06.593: //155971/8BF86E8D9869/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bK7sbeotau2tahtkk4sfcobdod2T39222 From: ;tag=sbc0802kbctotak To: ;tag=B2525998-10C3 Date: Mon, 27 Jul 2015 13:38:06 GMT Call-ID: isbcf4htkpkdudeuku4cacasoaoeabuk77kh@SoftX3000 Server: Cisco-SIPGateway/IOS-15.2.4.M6a CSeq: 1 OPTIONS Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Allow-Events: telephone-event Accept: application/sdp Supported: timer,resource-priority,replaces,sdp-anat Content-Type: application/sdp Content-Length: 374 v=0 o=CiscoSystemsSIP-GW-UserAgent 2866 1317 IN IP4 10.68.12.194 s=SIP Call c=IN IP4 172.20.201.18 t=0 0 m=audio 0 RTP/AVP 18 0 8 9 4 2 15 c=IN IP4 172.20.201.18 m=image 0 udptl t38 c=IN IP4 172.20.201.18 a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:200 a=T38FaxMaxDatagram:320 a=T38FaxUdpEC:t38UDPRedundancy 000649: Jul 27 16:38:26.637: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: OPTIONS sip:10.68.12.194:5060 SIP/2.0 Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKfo2dc2s2k7bd2hcba7uhhos27T23302 Call-ID: isbcsdu2caf2afhbkttffh44psdko27p7sab@SoftX3000 From: ;tag=sbc0804peso4ekb To: CSeq: 1 OPTIONS Max-Forwards: 70 Content-Length: 0 000650: Jul 27 16:38:26.637: //-1/97EAEE04986A/SIP/State/sipSPIChangeState: 0x310AE10 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 000651: Jul 27 16:38:26.637: //155972/97EAEE04986A/SIP/State/sipSPIChangeState: 0x310AE10 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE) 000652: Jul 27 16:38:26.637: //155972/97EAEE04986A/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKfo2dc2s2k7bd2hcba7uhhos27T23302 From: ;tag=sbc0804peso4ekb To: ;tag=B252A7E4-1AC5 Date: Mon, 27 Jul 2015 13:38:26 GMT Call-ID: isbcsdu2caf2afhbkttffh44psdko27p7sab@SoftX3000 Server: Cisco-SIPGateway/IOS-15.2.4.M6a CSeq: 1 OPTIONS Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Allow-Events: telephone-event Accept: application/sdp Supported: timer,resource-priority,replaces,sdp-anat Content-Type: application/sdp Content-Length: 373 v=0 o=CiscoSystemsSIP-GW-UserAgent 9593 312 IN IP4 10.68.12.194 s=SIP Call c=IN IP4 172.20.201.18 t=0 0 m=audio 0 RTP/AVP 18 0 8 9 4 2 15 c=IN IP4 172.20.201.18 m=image 0 udptl t38 c=IN IP4 172.20.201.18 a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:200 a=T38FaxMaxDatagram:320 a=T38FaxUdpEC:t38UDPRedundancy