CME call not routing to skype

yakubpangat · ‎07-26-2017

Hi,

Skype configured in CME9.1.. But call not going outside. Hope you support. Pls find the below debug details.

debug ccsip all
This may severely impact system performance. Continue? [confirm]
All SIP Call tracing is enabled
CUCME-HO#
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x230B9060) with key=[1116] to table
*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:
*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/ccsip_iwf_init:
*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:
*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization: Entry...
*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/ccsip_ipip_media_forking_init: MF: Queue is initialised..
*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/ccsipRegisterSetTargetInfo: p2p mode with Registrar Server = dns:sip.skype.com
*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/ccsipRegisterSetTargetInfo: Parsing The Registrar Address
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : sip.skype.com target_port : 5060

*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/sipSPIOutboundProxyReuse: Do not reuse Outbound Proxy IP adress and Port
"*Jul 26 12:44:54.649: //-1/000000000000/SIP/State/sipSPIChangeState: 0x230B9060 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)"
*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/ccsip_spi_registrar_add_expires_header: Inside ccsip_spi_registrar_add_expires_header for Expires
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_OUTBOUND_REGISTER
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIIncrementOverloadCount: Local 1 Global 1
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 40
*Jul 26 12:44:54.649: //-1/000000000000/SIP/Info/act_idle_outgoing_register: In act_idle_outgoing_register

*Jul 26 12:44:54.649: //1210/000000000000/SIP/Info/act_idle_outgoing_register: Send REGISTER to sip.skype.com:5060

*Jul 26 12:44:54.649: //1210/000000000000/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.
*Jul 26 12:44:54.649: //1210/000000000000/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x230B9060 key=55DD9A65-70FD11E7-810F958D-2C32E426
*Jul 26 12:44:54.649: //1210/000000000000/SIP/Info/act_idle_outgoing_register: Locally Resolved IP:78.141.179.70:5060
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: ip_best_local_address 10.220.100.10 for SIP
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 10.220.100.10
*Jul 26 12:44:54.649: //1210/000000000000/SIP/Info/sipSPIPresendProcessing: Presend Processing called for 7 event
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
"*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT"
*Jul 26 12:44:54.649: //1210/000000000000/SIP/Info/sipSPISendRegister: Associated container=0x3D865A98 to Register
*Jul 26 12:44:54.649: //1210/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
"*Jul 26 12:44:54.649: //1210/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE"
"*Jul 26 12:44:54.649: //1210/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x39E6DF28, addr=78.141.179.70, port=5060, sentBy_port=0, local_addr=, is_req=1, tra"
"nsport=1, switch=0, callBack=0x347DF808"
*Jul 26 12:44:54.649: //1210/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Jul 26 12:44:54.649: //1210/000000000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
"*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:78.141.179.70, rport:5060 with laddr:"

*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x230B9060 with connection=0x22AA6F84 context list
*Jul 26 12:44:54.649: //1210/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x39E6DF28
"*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x39E6DF28, addr=78.141.179.70, port=5060, local_addr=, connId=2 for"
UDP
"*Jul 26 12:44:54.649: //1210/000000000000/SIP/State/sipSPIChangeState: 0x230B9060 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE"
_NONE)
"*Jul 26 12:44:54.649: //1210/000000000000/SIP/State/sipSPIChangeState: 0x230B9060 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_R"
"EGISTER, SUBSTATE_NONE)"
*Jul 26 12:44:54.649: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:sip.skype.com:5060 SIP/2.0
Via: SIP/2.0/UDP 10.220.100.10:5060;branch=z9hG4bK46B9E0
From: <sip:99052589648856@sip.skype.com>;tag=588C9E0-D2E
To: <sip:99052589648856@sip.skype.com>
"Date: Wed, 26 Jul 2017 12:44:54 GMT"
Call-ID: 55DD9A65-70FD11E7-810F958D-2C32E426
User-Agent: Cisco-SIPGateway/IOS-15.2.4.M5
Max-Forwards: 70
Timestamp: 1501073094
CSeq: 311 REGISTER
Contact: <sip:99052589648856@10.220.100.10:5060>
Expires: 3600
Supported: path
Content-Length: 0


"*Jul 26 12:44:54.761: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [78.141.179.70]:5060, local_address:[ - ]"
*Jul 26 12:44:54.761: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Jul 26 12:44:54.761: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
*Jul 26 12:44:54.761: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Jul 26 12:44:54.761: //1210/000000000000/SIP/Info/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK46B9E0 from via branch list
*Jul 26 12:44:54.761: //1210/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
From: <sip:99052589648856@sip.skype.com>;tag=588C9E0-D2E
To: <sip:99052589648856@sip.skype.com>;tag=46b38d4e-8df3ad1f-0-7f066044-0
Call-ID: 55DD9A65-70FD11E7-810F958D-2C32E426
CSeq: 311 REGISTER
Via: SIP/2.0/UDP 10.220.100.10:5060;branch=z9hG4bK46B9E0;rport=60179;received=212.12.167.202
Contact: <sip:99052589648856@10.220.100.10:5060;transport=udp>;expires=45
Expires: 45
Content-Length: 0


*Jul 26 12:44:54.761: //1210/000000000000/SIP/Info/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX
*Jul 26 12:44:54.761: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDecrementOverloadCount: Count:Local 0 Global 0
*Jul 26 12:44:54.761: //1210/000000000000/SIP/Info/sipSPIRegPthruProcessResponse: Processing response w/ resp code == 200
*Jul 26 12:44:54.761: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetRPCBFromRCB: Retreiving RCB [0x39E8E0D4] from RPCB [0x0]
*Jul 26 12:44:54.761: //1210/000000000000/SIP/Error/sipSPIRegPthruProcessResponse:
Error NO RPCB

*Jul 26 12:44:54.761: //-1/xxxxxxxxxxxx/SIP/Info/ccsipRegisterStartRCBTimer: Starting timer for pattern 99052589648856 for 60 seconds
*Jul 26 12:44:54.761: //1210/000000000000/SIP/Info/ccsip_gw_register_process_response: No P-Assoicated-URI present in Register Response
*Jul 26 12:44:54.761: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[1116] removed.
*Jul 26 12:44:54.761: //1210/000000000000/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.
*Jul 26 12:44:54.761: //1210/000000000000/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x230B9060 key=55DD9A65-70FD11E7-810F958D-2C32E426
*Jul 26 12:44:54.761: //1210/000000000000/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
"*Jul 26 12:44:54.761: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x3CC176C4, addr=78.141.179.70, port=5060, local_addr=, unreg"
istering context=0x230B9060
*Jul 26 12:44:54.761: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection: Purging context gcb=0x230B9060 from the connection=0x22AA6F84 context list
*Jul 26 12:44:54.765: //1210/000000000000/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
*Jul 26 12:44:54.765: //1210/000000000000/SIP/Info/ccsip_offer_ans_delete:
*Jul 26 12:44:54.765: //1210/000000000000/SIP/Info/ccsip_iwf_delete:
*Jul 26 12:44:54.765: //1210/000000000000/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 0x230B9060
*Jul 26 12:44:54.765: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[1116]
*Jul 26 12:45:02.413: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x230B9060) with key=[1117] to table
*Jul 26 12:45:02.413: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:
*Jul 26 12:45:02.413: //-1/000000000000/SIP/Info/ccsip_iwf_init:
*Jul 26 12:45:02.413: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:
*Jul 26 12:45:02.413: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization: Entry...
*Jul 26 12:45:02.413: //-1/000000000000/SIP/Info/ccsip_ipip_media_forking_init: MF: Queue is initialised..
"*Jul 26 12:45:02.413: //1211/000000000000/SIP/State/sipSPIChangeState: 0x230B9060 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)"
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_call_setup_request: Set Protocol information
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Error/ccsip_ipip_media_forking_read_from_TDContainer:
MF: Unable to read data from TD Container..
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Error/ccsip_ipip_media_forking_forked_leg_config:
MF: TD container cannot be read/container is NULL. Setting of forked call leg failed..
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:
"*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 183"
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_iwf_process_event:
"*Jul 26 12:45:02.413: //1211/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
*Jul 26 12:45:02.413: //1211/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_mode_sip_sip_md_snr:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_mode_sip_sip_ed_snr:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_mode_sip_sip_md:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_mode_sip_sip_ed:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_mode_sip_h32x_in_set_mode:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_mode_sip_h323_in_set_mode:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM
*Jul 26 12:45:02.413: //1211/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sccp_early_dialog_container
*Jul 26 12:45:02.413: //1211/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:
"*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5"
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_iwf_process_event:
"*Jul 26 12:45:02.413: //1211/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF"
_EV_SET_FLOW_MODE
*Jul 26 12:45:02.413: //1211/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_fa2ft_md_flow_mode_transition:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200
*Jul 26 12:45:02.413: //1211/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200
*Jul 26 12:45:02.413: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : sip.skype.com target_port : 5060

*Jul 26 12:45:02.413: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*Jul 26 12:45:02.413: //1211/0BF8F95381BF/SIP/Info/ccsip_call_setup_request: Incrementing call counter to [1] in dial-peer [1014]
*Jul 26 12:45:02.417: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 4BB to table
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec    bytes: 0
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPI_ipip_store_config_info: Setting mid_call_config_info = 0x0 for callid = 1211
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
"*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 50 and offer-all is = FALSE"
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=1209
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Error/ccsip_ipip_media_forking_update_preferred_codec:
MF: Not a Forked SIP leg..
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIGetCallConfig: Incoming: No defer BYE for last
                              call stats
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/ccsip_ipip_media_forking_anchor_leg_config: MF: en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer has no media class recorder.
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIMFChangeState: MF: Prev state = 0 & New state = -1
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/ccsip_ipip_media_forking_anchor_leg_reset: MF: Anchor leg config reset done...
"*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/ccsipInitDSCPPolicyInfo: No DSCP Profile configured, No RPH 2 DSCP Mapping and DSCP policing"
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIGetCallConfig: Initilise the DSCP policy
"*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/populate_vcc_data: Using Voice Class Codec, tag = 50 and offer-all is = FALSE"
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/preprocessSetup:
" This is a not a SIGO Call -, could be DM call "
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/ccsip_iwf_process_event:
"*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF"
_EV_INIT_CALL_SETUP
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/preprocessSetup: SIP-TDM or TCL/VXML app case
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sip_gw_pre_setup_update_stream_media_direction: peer_callID = 1209
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sip_gw_pre_setup_update_stream_media_direction: peer_channels/stream is NULL
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sip_gw_pre_setup_add_sdp_container: DNS/ENUM resolution required; Deferred Creating SDP
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted BW Option 0 flow mode flow-through
audio bw 0 bps video bw 0 bps fax bw 0 bps total bw 0 bps accounted bw 0 bps
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacUpdateInterfaceBw: NOP
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (initial offer) accounted bw set to 0
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 1014
"*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 0 bps "
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIValidateGtd: Signal Forward disabled
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg
"*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE"
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.
"*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIAddPrivacyandIdentityInfo: ccb->local_host_name,ccb->src_addr_str is NULL"
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Error/sipSPI_ipip_set_history_info_header:
ccb->src_addr_str is NULL
*Jul 26 12:45:02.417: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_DNS_RESOLVE
"*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/State/sipSPIChangeState: 0x230B9060 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_SENT_DNS)"
"*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/State/sipSPIChangeState: 0x230B9060 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (STATE_IDLE, SUBSTATE_SENT_DNS)"
*Jul 26 12:45:02.417: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Jul 26 12:45:02.417: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: ip_best_local_address 10.220.100.10 for SIP
*Jul 26 12:45:02.417: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 10.220.100.10
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x230B9060 key=139B5228-713711E7-81C4958D-2C32E426@10.220.100.10
*Jul 26 12:45:02.417: //1211/0BF8F95381BF/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = 139B5228-713711E7-81C4958D-2C32E426@10.220.100.10
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: TYPE SRV query for _sip._udp.sip.skype.com and type:1
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: Server Name 5.sip.skype.com
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: Priority 0 Weight 0 Port 5060
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: Server Name 3.sip.skype.com
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: Priority 1 Weight 0 Port 5060
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: Selected Server is 5.sip.skype.com
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_query: TYPE A query successful for 5.sip.skype.com
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: IP Address of 5.sip.skype.com is:

*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: 78.141.179.70

*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 43
"*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/State/sipSPIChangeState: 0x230B9060 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (STATE_IDLE, SUBSTATE_NONE)"
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICacheHostToCCB: sipSPICacheHostToCCB dnsResponse.num_hosts = 1
"*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICacheHostToCCB: IP Address No. 1, IP address 78.141.179.70"
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: ip_best_local_address 10.220.100.10 for SIP
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 10.220.100.10
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: ip_best_local_address 10.220.100.10 for SIP
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 10.220.100.10
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container
"*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing ""id"" value from Privacy"
"*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1"
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: ip_best_local_address 10.220.100.10 for SIP
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: return addr 10.220.100.10
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.220.100.10
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/ccsip_ipip_media_forking_add_forking_stream: MF: Not a forked SPI leg..
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/ccsip_ipip_media_forking_update_forking_stream: MF: Not a forked SPI leg..
"SIP: (1211) Group (a= group line) attribute, level 65535 instance 1 not found."
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: ip_best_local_address 10.220.100.10 for SIP
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 10.220.100.10
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 16482 for stream 1
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIDoBearerCapToCodecMapping: Bearer capability to Codec Mapping: DISABLED

"*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101"
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: calculating max bw from preffered codecs (local offer)
"SIP: (1211) Group (a= group line) attribute, level 65535 instance 1 not found."
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (excluding pak overhead) from preffered codecs: codec g711ulaw   bw 64000
index 1
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: audio caps channel idx not found !!!!
"*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20"
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (including pak overhead) from preffered codecs: codec g711ulaw   bw 80000
*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_IDLE
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Media/sipSPIProcessRtpSessions: No active streams.
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted BW Option 5 flow mode flow-through
audio bw 80000 bps video bw 0 bps fax bw 0 bps total bw 80000 bps accounted bw 0 bps
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacUpdateInterfaceBw: bwcac acquiring interface GigabitEthernet0/0 bw 0
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (interface changed)
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 1014
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacIsInterfaceBwAvailable: bwcac interface bw threshold not configured
"*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 80000 bps "
"*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 78.141.179.70,Port 5060, Transport 1, SentBy Port 5060"
"*Jul 26 12:45:02.421: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT"
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
"*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:3D865BA0"
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/Session-Timer/sipSTSLSRReqSend: Session timer is not required
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/Session-Timer/sipSTSLMain:
   "SE: 0;refresher:none peer refresher:none, flags:2000, posted event:E_STSL_INVALID_PEER_EVENT, reason:4"
   "Configured SE:1800, Configured Min-SE:1800"
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/ccsip_offer_ans_handle_sent_sdp:
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/ccsip_offer_ans_process_event:
"*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_IDLE, event:E_SIP_INVI"
TE_SDP_SENT
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/ccsip_offer_ans_is_invite_offer_valid: - 1
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/ccsip_offer_ans_common_offer_sent_hdlr:
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/ccsip_iwf_handle_network_event:
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/ccsip_iwf_process_event:
"*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF"
_EV_SENT_SDP
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/is_sent_sccp_do_video_inactive:
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/is_mode_sip_sccp_do_video:
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/is_mode_sip_sccp_do_video:
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/Info/sip_iwf_def_ed_sent_sdp_offer_hdlr:
*Jul 26 12:45:02.421: //1211/0BF8F95381BF/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT
*Jul 26 12:45:02.425: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar host retrieved : sip.skype.com
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/ccsip_ipip_media_forking_get_forked_recording_data: MF: Not an Forked leg..
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sipSPICreateRecParticipantHeaders: X-Cisco-Recording-Participant header not added.
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Event/sipSPICreateRpid: Received Octet3A=0x00 -> Setting ;screen=no ;privacy=off
"SIP: (1211) Group (a= group line) attribute, level 65535 instance 1 not found."
"*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat"
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sipSPISendInvite: Associated container=0x3D865BA0 to Invite
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
"*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE"
"*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Transport/sipSPITransportSendMessage: msg=0x2312B8C8, addr=78.141.179.70, port=5060, sentBy_port=0, local_addr=, is_req=1, tra"
"nsport=1, switch=0, callBack=0x347DA8C4"
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
"*Jul 26 12:45:02.425: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:78.141.179.70, rport:5060 with laddr:"

*Jul 26 12:45:02.425: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x230B9060 with connection=0x22AA6F84 context list
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2312B8C8
"*Jul 26 12:45:02.425: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2312B8C8, addr=78.141.179.70, port=5060, local_addr=, connId=2 for"
UDP
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE
*Jul 26 12:45:02.425: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued.
"*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/State/sipSPIChangeState: 0x230B9060 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE)"
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_ADDING
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sipSPIAddStream:
"set stream_callid from ccb->ccCallID:0x4BB, media_type:0"
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 1211) to the VOIP RTP library
"*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1"
"*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr"
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.220.100.10
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sipSPIUpdateRtcpSession:
ccb->flags != LOOPBACK

*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info
   "laddr = 10.220.100.10, lport = 16482, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE"
   "src_callid = 1211, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY"
   "media_ip_addr = - , vrf tableid = 0 media_addr_type = 1"   negotiated_bandwidth (kbps) = 0
"*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one"
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sipSPICreateRtpSession: sess: 3BB52F4C do_rtcp:0
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Media/sipSPICreateRtpSession: stun is disabled
"*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sipSPIUpdateRtcpSession: Not able to Associate DSCP Profile with GCCB dscp_policy = 0x0, IS_SIPSPI_MODE_IN_SIP_SIP = 0 ds"
"cpPolicySeviceBlock = 0x0 , stream->qos_info = 0x0 "
*Jul 26 12:45:02.425: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIUpdateRtcpSession: Voice quality monitoring is not enabled for this RTP session due to sdp passthru enabled
"*Jul 26 12:45:02.425: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIUpdateRtcpSession: VQM: gccb=0x0, gccb->callId=0, ccb->ccCallID=1211"
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sipSPIUpdateRtcpSession:
DTMF inb/oob disabled
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/State/sipSPIChangeStreamState: Stream (callid = 1211) State changed from (STREAM_ADDING) to (STREAM_ACTIVE)
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Info/sipSPIUpdateCallEntry:
Call 1211 set InfoType to SPEECH
*Jul 26 12:45:02.425: //1211/0BF8F95381BF/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:00966535537266@sip.skype.com:5060 SIP/2.0
Via: SIP/2.0/UDP 10.220.100.10:5060;branch=z9hG4bK46C2622
Remote-Party-ID: <sip:8409@10.220.100.10>;party=calling;screen=no;privacy=off
From: <sip:8409@sip.skype.com>;tag=588E840-B4A
To: <sip:00966535537266@sip.skype.com>
"Date: Wed, 26 Jul 2017 12:45:02 GMT"
Call-ID: 139B5228-713711E7-81C4958D-2C32E426@10.220.100.10
"Supported: 100rel,timer,resource-priority,replaces,sdp-anat"
Min-SE: 1800
Cisco-Guid: 0200866131-1899434471-2176816525-0741532710
User-Agent: Cisco-SIPGateway/IOS-15.2.4.M5
"Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER"
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1501073102
Contact: <sip:8409@10.220.100.10:5060>
Expires: 180
Allow-Events: telephone-event
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 367

v=0
o=CiscoSystemsSIP-GW-UserAgent 4996 3164 IN IP4 10.220.100.10
s=SIP Call
c=IN IP4 10.220.100.10
t=0 0
m=audio 16482 RTP/AVP 18 0 8 4 101
c=IN IP4 10.220.100.10
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=yes
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=fmtp:4 bitrate=5.3;annexa=yes
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

"*Jul 26 12:45:03.009: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [78.141.179.70]:5060, local_address:[ - ]"
"*Jul 26 12:45:03.009: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT"
*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Info/ccsip_ipip_media_forking_get_forked_recording_data: MF: Not an Forked leg..
*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Info/sipSPICreateRecParticipantHeaders: X-Cisco-Recording-Participant header not added.
*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Event/sipSPICreateRpid: Received Octet3A=0x00 -> Setting ;screen=no ;privacy=off
"SIP: (1211) Group (a= group line) attribute, level 65535 instance 1 not found."
"*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat"
*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Info/sipSPISendInvite: Associated container=0x3D865BA0 to Invite
*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
"*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE"
"*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Transport/sipSPITransportSendMessage: msg=0x3B6ECDB8, addr=78.141.179.70, port=5060, sentBy_port=0, local_addr=, is_req=1, tra"
"nsport=1, switch=0, callBack=0x0"
*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
"*Jul 26 12:45:03.009: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:78.141.179.70, rport:5060 with laddr:"

*Jul 26 12:45:03.009: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x230B9060 is already on connection=0x22AA6F84 context_list
*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x3B6ECDB8
"*Jul 26 12:45:03.009: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x3B6ECDB8, addr=78.141.179.70, port=5060, local_addr=, connId=2 for"
UDP
*Jul 26 12:45:03.009: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Jul 26 12:45:03.009: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
*Jul 26 12:45:03.009: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Jul 26 12:45:03.009: //1211/0BF8F95381BF/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:00966535537266@sip.skype.com:5060 SIP/2.0
Via: SIP/2.0/UDP 10.220.100.10:5060;branch=z9hG4bK46C2622
From: <sip:8409@sip.skype.com>;tag=588E840-B4A
To: <sip:00966535537266@sip.skype.com>;tag=46b38d4e-13c4-5978951e-fc2abd3f-246daffd
"Date: Wed, 26 Jul 2017 12:45:03 GMT"
Call-ID: 139B5228-713711E7-81C4958D-2C32E426@10.220.100.10
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0