Buy or Renew
Buy or Renew
Cisco + Splunk: It’s a new day for your data. Learn more.
 
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
cancel
Start a conversation
3705
Views
12
Helpful
15
Replies

CUE auto attendant busy signal

borhen bouallagui
Level 1
Level 1

‎03-13-2013 05:00 AM - edited ‎03-16-2019 04:14 PM

hello

i have a problem with cisco unity express auto attendant , i get a busy signal when making call to AA extension

debug ccsip all:

Mar 13 12:48:42.757: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x2B83DFD0) with key=[16958] to table

Mar 13 12:48:42.757: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:

Mar 13 12:48:42.757: //-1/000000000000/SIP/Info/ccsip_iwf_init: 

Mar 13 12:48:42.757: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:

Mar 13 12:48:42.757: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization:  Entry...

Mar 13 12:48:42.757: //605598/000000000000/SIP/State/sipSPIChangeState: 0x2B83DFD0 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)

Mar 13 12:48:42.757: //605598/000000000000/SIP/Info/ccsip_call_setup_request: Set Protocol information

Mar 13 12:48:42.757: //605598/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:

Mar 13 12:48:42.757: //605598/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 176

Mar 13 12:48:42.757: //605598/000000000000/SIP/Info/ccsip_iwf_process_event: 

Mar 13 12:48:42.757: //605598/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

Mar 13 12:48:42.761: //605598/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/is_mode_sip_sip_md:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/is_mode_sip_sip_ed:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/is_mode_sip_h32x_in_set_mode:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/is_mode_sip_h323_in_set_mode:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM

Mar 13 12:48:42.761: //605598/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_default_early_dialog_container

Mar 13 12:48:42.761: //605598/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_iwf_process_event: 

Mar 13 12:48:42.761: //605598/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_FLOW_MODE

Mar 13 12:48:42.761: //605598/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/is_fa2ft_md_flow_mode_transition:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200

Mar 13 12:48:42.761: //605598/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200

Mar 13 12:48:42.761: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 192.168.2.252 target_port : 5060

Mar 13 12:48:42.761: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/ccsip_call_setup_request: Incrementing call counter to [-8] in dial-peer [2001]

Mar 13 12:48:42.761: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 93D9E to table

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec    bytes: 0

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIGetCallConfig: Media forking disabled

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIGetCallConfig: Checking Video Type Rate=-1 video_codec_allowed=1F

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=605597

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIGetCallConfig: Media forking disabled

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/preprocessSetup:

This is a not a SIGO Call -, could be DM call

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/ccsip_iwf_process_event: 

Mar 13 12:48:42.761: //605598/2BA783CFABD9/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_INIT_CALL_SETUP

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 192.168.2.253

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 12:48:42.761: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 28348 for stream 1

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101

Mar 13 12:48:42.761: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20

Mar 13 12:48:42.761: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Media/sipSPIProcessRtpSessions: No active streams.

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIValidateGtd: Signal Forward disabled

Mar 13 12:48:42.761: //605598/2BA783CFABD9/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x2B83DFD0 key=2BA8BC1F-8B1311E2-ABDEE1AE-D7673161@192.168.2.253

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = 2BA8BC1F-8B1311E2-ABDEE1AE-D7673161@192.168.2.253

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 192.168.2.252,Port 5060, Transport 2, SentBy Port 5060

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:3246A5BC

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/Session-Timer/sipSTSLSRReqSend: Session timer is not required

Mar 13 12:48:42.765: //605598/2BA783CFABD9/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

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/ccsip_offer_ans_handle_sent_sdp:

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/ccsip_offer_ans_process_event:

Mar 13 12:48:42.765: //605598/2BA783CFABD9/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

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/ccsip_offer_ans_is_invite_offer_valid:  - 1

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/ccsip_offer_ans_common_offer_sent_hdlr:

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/ccsip_iwf_handle_network_event:

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/ccsip_iwf_process_event: 

Mar 13 12:48:42.765: //605598/2BA783CFABD9/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_SENT_SDP

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sip_iwf_def_ed_sent_sdp_offer_hdlr:

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured

SIP: (605598) Group (a= group line) attribute, level 65535 instance 1 not found.

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPISendInvite: Associated container=0x3246A5BC to Invite

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Transport/sipSPITransportSendMessage: msg=0x2B96BE78, addr=192.168.2.252, port=5060, sentBy_port=0, local_addr=192.168.2.253, is_req=1, transport=2, switch=0, callBack=0x251CB50C

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging timer initiated for holder=0x2AF8EDE0,addr=192.168.2.252

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipCreateConnHolder: Created new holder=0x2AF8EDE0, addr=192.168.2.252; nailed=FALSE

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostRequestConnection: Posting TCP conn create request for addr=192.168.2.252, port=5060, context=0x3177B244

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetConnWaitTimer: Wait timer set for connection=0x2AEC1ABC,addr=192.168.2.252, port=5060

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetConnWaitTimer:

Wait Conn Timer started for 5000 msec

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipCreateConnInstance: Created new initiated conn=0x2AEC1ABC, connid=-1, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, transport=TCP

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:192.168.2.252, rport:5060 with laddr:192.168.2.253

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x2B83DFD0 with connection=0x2AEC1ABC

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Transport/sipSPITransportSendMessage: Deferred sending msg=0x2B96BE78

Mar 13 12:48:42.765: //605598/2BA783CFABD9/SIP/Info/sipSPIUpdateCallEntry:

Call 605598 set InfoType to SPEECH

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 56

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWConnectionCreated: context=0x3177B244

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessConnCreated: gConnTab=0x3177B244, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, connid=3, transport=TCP

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessConnCreated: connection instance created for addr:192.168.2.252, port:5060 local_addr=192.168.2.253 local_port=50234

Mar 13 12:48:42.765: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnectionCreated: Moving connection=0x2AEC1ABC, connid=3state to pending

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Error/sip_tcp_createconnfailed_to_spi: TCP create conn failed to SPI (addr:192.168.2.252, port:5060)

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 57

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWConnectionFailed: context=0x3177B244

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessConnFailed: gConnTab=0x3177B244, addr=192.168.2.252, port=5060, local_addr = 192.168.2.253, transport=TCP

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportStopConnWaitTimer: Wait timer stopped for connection=0x2AEC1ABC,addr=192.168.2.252, port=5060

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Transport/sipTransportPostInternalMsg: Posting Internal Msg type=1

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostCloseConnection: Posting TCP conn close for addr=192.168.2.252, port=5060, local_addr=192.168.2.253, connid=3

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Transport/sipDeleteConnInstance: Deleted conn=0x2AEC1ABC, connid=3, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, transport=TCP

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 61

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Error/sipTransportPostSendFailure: Posting send failure msg

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 53

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Error/act_idle_send_msg_failure: Send Error to 192.168.2.252:5060 for transport TCP

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/ccsip_set_cc_cause_for_spi_err: Categorized cause:38, category:186

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[605598], src[6]

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(38) for outgoing call

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQSIG: No Inbound Container Created !!!

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQ931: No Inbound Container Created !!!

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/State/sipSPIChangeState: 0x2B83DFD0 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_DISCONNECTING, SUBSTATE_NONE)

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/ccsip_call_statistics: Stats are not supported for IPIP call.

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_purge_entry: Socket fd: 0 closed for connid 3 with address: 192.168.2.252, remote port: 5060

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 8

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT

Mar 13 12:48:42.769: //605598/2BA783CFABD9/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

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPIIcpifUpdate: CallState: 2 Playout: 0 DiscTime:90740177 ConnTime 0

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/State/sipSPIChangeState: 0x2B83DFD0 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DEAD, SUBSTATE_NONE)

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Call/sipSPICallInfo:

The Call Setup Information is:

Call Control Block (CCB) : 0x2B83DFD0

State of The Call        : STATE_DEAD

TCP Sockets Used         : YES

Calling Number           :

Called Number            : 400

Source IP Address (Sig  ): 192.168.2.253

Destn SIP Req Addr:Port  : 192.168.2.252:5060

Destn SIP Resp Addr:Port : 192.168.2.252:5060

Destination Name         : 192.168.2.252

Mar 13 12:48:42.769: //605598/2BA783CFABD9/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): 192.168.2.253

Source IP Port    (Media): 28348

Destn  IP Address (Media):  -

Destn  IP Port    (Media): 0

Orig Destn IP Address:Port (Media): [ - ]:0

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Call/sipSPICallInfo:

Disconnect Cause (CC)    : 38

Disconnect Cause (SIP)   : 503

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 93D9E

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[16958] removed.

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x2B83DFD0 key=2BA8BC1F-8B1311E2-ABDEE1AE-D7673161@192.168.2.253

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/ccsip_offer_ans_delete:

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/ccsip_iwf_delete: 

Mar 13 12:48:42.769: //605598/2BA783CFABD9/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 2B83DFD0

Mar 13 12:48:42.769: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[16958]

Labels:
0 Helpful
15 Replies 15

chrysostomos1980
Level 5
Level 5

‎03-13-2013 05:10 AM

Hi

Pls post the voip dial peer for autoattendant

Regards

chrysostomos

Please rate all useful posts Regards Chrysostomos ""The Most Successful People Are Those Who Are Good At Plan B""
0 Helpful

borhen bouallagui
Level 1
Level 1
In response to chrysostomos1980

‎03-13-2013 05:16 AM

dial-peer voice 2001 voip

description ** cue auto attendant number **

destination-pattern 400

b2bua

session protocol sipv2

session target ipv4:192.168.2.252

voice-class sip outbound-proxy ipv4:192.168.2.252

dtmf-relay rtp-nte

codec g711ulaw

no vad

0 Helpful

chrysostomos1980
Level 5
Level 5
In response to borhen bouallagui

‎03-13-2013 05:24 AM

Hi

Pls  use the below dial peer and send me the debug ccsip messages

Also can we have the full config

dial-peer voice 2001 voip

description ** cue auto attendant number **

destination-pattern 400

session protocol sipv2

session target ipv4:192.168.2.252

dtmf-relay rtp-nte

codec g711ulaw

no vad

Please rate all useful posts Regards Chrysostomos ""The Most Successful People Are Those Who Are Good At Plan B""

borhen bouallagui
Level 1
Level 1

‎03-13-2013 05:39 AM

Mar 13 13:28:19.169: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x2B83DFD0) with key=[16969] to table

Mar 13 13:28:19.169: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:

Mar 13 13:28:19.169: //-1/000000000000/SIP/Info/ccsip_iwf_init: 

Mar 13 13:28:19.169: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:

Mar 13 13:28:19.169: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization:  Entry...

Mar 13 13:28:19.169: //605619/000000000000/SIP/State/sipSPIChangeState: 0x2B83DFD0 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/ccsip_call_setup_request: Set Protocol information

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 176

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:19.169: //605619/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

Mar 13 13:28:19.169: //605619/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/is_mode_sip_sip_md:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/is_mode_sip_sip_ed:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/is_mode_sip_h32x_in_set_mode:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/is_mode_sip_h323_in_set_mode:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:19.169: //605619/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM

Mar 13 13:28:19.173: //605619/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_default_early_dialog_container

Mar 13 13:28:19.173: //605619/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:19.173: //605619/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_FLOW_MODE

Mar 13 13:28:19.173: //605619/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/is_fa2ft_md_flow_mode_transition:

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200

Mar 13 13:28:19.173: //605619/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200

Mar 13 13:28:19.173: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 192.168.2.252 target_port : 5060

Mar 13 13:28:19.173: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/ccsip_call_setup_request: Incrementing call counter to [1] in dial-peer [2001]

Mar 13 13:28:19.173: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 93DB3 to table

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec    bytes: 0

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIGetCallConfig: Media forking disabled

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIGetCallConfig: Checking Video Type Rate=-1 video_codec_allowed=1F

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=605618

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIGetCallConfig: Media forking disabled

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/preprocessSetup:

This is a not a SIGO Call -, could be DM call

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:19.173: //605619/B41A9423AC10/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_INIT_CALL_SETUP

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 192.168.2.253

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 13:28:19.173: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 17790 for stream 1

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101

Mar 13 13:28:19.173: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20

Mar 13 13:28:19.173: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Media/sipSPIProcessRtpSessions: No active streams.

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIValidateGtd: Signal Forward disabled

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled

Mar 13 13:28:19.173: //605619/B41A9423AC10/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x2B83DFD0 key=B41C689B-8B1811E2-AC15E1AE-D7673161@192.168.2.253

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = B41C689B-8B1811E2-AC15E1AE-D7673161@192.168.2.253

Mar 13 13:28:19.177: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 192.168.2.252,Port 5060, Transport 1, SentBy Port 5060

Mar 13 13:28:19.177: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:32469384

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/Session-Timer/sipSTSLSRReqSend: Session timer is not required

Mar 13 13:28:19.177: //605619/B41A9423AC10/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

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/ccsip_offer_ans_handle_sent_sdp:

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/ccsip_offer_ans_process_event:

Mar 13 13:28:19.177: //605619/B41A9423AC10/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

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/ccsip_offer_ans_is_invite_offer_valid:  - 1

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/ccsip_offer_ans_common_offer_sent_hdlr:

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/ccsip_iwf_handle_network_event:

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:19.177: //605619/B41A9423AC10/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_SENT_SDP

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sip_iwf_def_ed_sent_sdp_offer_hdlr:

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT

Mar 13 13:28:19.177: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured

SIP: (605619) Group (a= group line) attribute, level 65535 instance 1 not found.

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sipSPISendInvite: Associated container=0x32469384 to Invite

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Transport/sipSPITransportSendMessage: msg=0x30C26A3C, addr=192.168.2.252, port=5060, sentBy_port=0, local_addr=192.168.2.253, is_req=1, transport=1, switch=0, callBack=0x251CB50C

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

Mar 13 13:28:19.177: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:192.168.2.252, rport:5060 with laddr:192.168.2.253

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x30C26A3C

Mar 13 13:28:19.177: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x30C26A3C, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, connId=4 for UDP

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE

Mar 13 13:28:19.177: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued.

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/State/sipSPIChangeState: 0x2B83DFD0 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_SENT_INVITE, SUBSTATE_NONE)

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 605619) to the VOIP RTP library

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 192.168.2.253

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info

    laddr = 192.168.2.253, lport = 17790, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE

    src_callid = 605619, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY

    media_ip_addr =  - , vrf tableid = 0 media_addr_type = 1

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sipSPICreateRtpSession: sess: 317D6D8C do_rtcp:0

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Media/sipSPICreateRtpSession: stun is disabled

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/State/sipSPIChangeStreamState: Stream (callid =  605619)  State changed from (STREAM_ADDING) to (STREAM_ACTIVE)

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Info/sipSPIUpdateCallEntry:

Call 605619 set InfoType to SPEECH

Mar 13 13:28:19.177: //605619/B41A9423AC10/SIP/Msg/ccsipDisplayMsg:

Sent:

INVITE sip:400@192.168.2.252:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK612234

From: <192.168.2.253>;tag=363A1D08-1431

To: <400>

Date: Wed, 13 Mar 2013 13:28:19 GMT

Call-ID: B41C689B-8B1811E2-AC15E1AE-D7673161@192.168.2.253

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE:  1800

Cisco-Guid: 3021640739-2333610466-2886787502-3613864289

User-Agent: Cisco-SIPGateway/IOS-12.x

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Max-Forwards: 70

Timestamp: 1363181299

Contact: <192.168.2.253:5060>

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 250

v=0

o=CiscoSystemsSIP-GW-UserAgent 7535 6317 IN IP4 192.168.2.253

s=SIP Call

c=IN IP4 192.168.2.253

t=0 0

m=audio 17790 RTP/AVP 0 101

c=IN IP4 192.168.2.253

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

Mar 13 13:28:19.181: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [192.168.2.252]:32777, local_address:[192.168.2.253]

Mar 13 13:28:19.181: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1

Mar 13 13:28:19.181: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0

Mar 13 13:28:19.181: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog

Mar 13 13:28:19.181: //605619/B41A9423AC10/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK612234

To: <400>

From: <192.168.2.253>;tag=363A1D08-1431

Call-ID: B41C689B-8B1811E2-AC15E1AE-D7673161@192.168.2.253

CSeq: 101 INVITE

Content-Length: 0

Timestamp: 1363181299

Mar 13 13:28:19.181: //605619/B41A9423AC10/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX

Mar 13 13:28:19.181: //605619/B41A9423AC10/SIP/State/sipSPIChangeState: 0x2B83DFD0 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE)  to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [192.168.2.252]:32777, local_address:[192.168.2.253]

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 486 Busy Here

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK612234

To: <400>;tag=ds130f322c

From: <192.168.2.253>;tag=363A1D08-1431

Call-ID: B41C689B-8B1811E2-AC15E1AE-D7673161@192.168.2.253

CSeq: 101 INVITE

Content-Length: 0

Allow: INVITE, BYE, CANCEL, ACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO

Cisco-Gcid: B41C689B-8B1811E2-AC15E1AE-D7673161@192.168.2.253

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/ccsip_offer_ans_handle_call_active:

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/ccsip_offer_ans_process_event:

Mar 13 13:28:19.221: //605619/B41A9423AC10/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

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/ccsip_offer_ans_process_event: O/A - cnfsm ret 2

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/sipSPISendAck: Associated container=0x3246AF04 to Ack

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Transport/sipSPITransportSendMessage: msg=0x324B6634, addr=192.168.2.252, port=5060, sentBy_port=0, local_addr=192.168.2.253, is_req=1, transport=1, switch=0, callBack=0x0

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:192.168.2.252, rport:5060 with laddr:192.168.2.253

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x324B6634

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x324B6634, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, connId=4 for UDP

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/act_recdproc_new_message_response: Received a 4/5/6xx message with StatusCode: 486

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[605619], src[4]

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(17) for outgoing call

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/State/sipSPIChangeState: 0x2B83DFD0 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)  to (STATE_DISCONNECTING, SUBSTATE_NONE)

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/ccsip_call_statistics: Requesting stats for callid=605619

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/ccsip_call_statistics: Stats request failed for callid=605619, dstCallID=-1, rc=-7

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT

Mar 13 13:28:19.221: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 8

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT

Mar 13 13:28:19.221: //605619/B41A9423AC10/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

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/sipSPIIcpifUpdate: CallState: 2 Playout: 0 DiscTime:90977822 ConnTime 0

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Media/sipSPIDestroyRtpSession: stream:320F78F0

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/State/sipSPIChangeState: 0x2B83DFD0 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DEAD, SUBSTATE_NONE)

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Call/sipSPICallInfo:

The Call Setup Information is:

Call Control Block (CCB) : 0x2B83DFD0

State of The Call        : STATE_DEAD

TCP Sockets Used         : NO

Calling Number           :

Called Number            : 400

Source IP Address (Sig  ): 192.168.2.253

Destn SIP Req Addr:Port  : 192.168.2.252:5060

Destn SIP Resp Addr:Port : 192.168.2.252:5060

Destination Name         : 192.168.2.252

Mar 13 13:28:19.221: //605619/B41A9423AC10/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): 192.168.2.253

Source IP Port    (Media): 17790

Destn  IP Address (Media):  -

Destn  IP Port    (Media): 0

Orig Destn IP Address:Port (Media): [ - ]:0

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Call/sipSPICallInfo:

Disconnect Cause (CC)    : 17

Disconnect Cause (SIP)   : 486

Mar 13 13:28:19.221: //605619/B41A9423AC10/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 93DB3

Mar 13 13:28:19.225: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

ACK sip:400@192.168.2.252:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK612234

From: <192.168.2.253>;tag=363A1D08-1431

To: <400>;tag=ds130f322c

Date: Wed, 13 Mar 2013 13:28:19 GMT

Call-ID: B41C689B-8B1811E2-AC15E1AE-D7673161@192.168.2.253

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: telephone-event

Content-Length: 0

Mar 13 13:28:34.169: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x2B82D200) with key=[16970] to table

Mar 13 13:28:34.169: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:

Mar 13 13:28:34.169: //-1/000000000000/SIP/Info/ccsip_iwf_init: 

Mar 13 13:28:34.169: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:

Mar 13 13:28:34.169: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization:  Entry...

Mar 13 13:28:34.169: //605621/000000000000/SIP/State/sipSPIChangeState: 0x2B82D200 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_call_setup_request: Set Protocol information

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 176

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:34.169: //605621/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

Mar 13 13:28:34.169: //605621/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/is_mode_sip_sip_md:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/is_mode_sip_sip_ed:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/is_mode_sip_h32x_in_set_mode:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/is_mode_sip_h323_in_set_mode:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM

Mar 13 13:28:34.169: //605621/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_default_early_dialog_container

Mar 13 13:28:34.169: //605621/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:34.169: //605621/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_FLOW_MODE

Mar 13 13:28:34.169: //605621/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/is_fa2ft_md_flow_mode_transition:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200

Mar 13 13:28:34.169: //605621/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200

Mar 13 13:28:34.169: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 192.168.2.252 target_port : 5060

Mar 13 13:28:34.169: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/ccsip_call_setup_request: Incrementing call counter to [1] in dial-peer [2001]

Mar 13 13:28:34.173: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 93DB5 to table

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec    bytes: 0

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIGetCallConfig: Media forking disabled

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIGetCallConfig: Checking Video Type Rate=-1 video_codec_allowed=1F

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=605620

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIGetCallConfig: Media forking disabled

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/preprocessSetup:

This is a not a SIGO Call -, could be DM call

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/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_INIT_CALL_SETUP

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 192.168.2.253

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 13:28:34.173: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 31324 for stream 1

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101

Mar 13 13:28:34.173: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20

Mar 13 13:28:34.173: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Media/sipSPIProcessRtpSessions: No active streams.

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIValidateGtd: Signal Forward disabled

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x2B82D200 key=BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

Mar 13 13:28:34.173: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 192.168.2.252,Port 5060, Transport 1, SentBy Port 5060

Mar 13 13:28:34.173: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:3246BBBC

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/Session-Timer/sipSTSLSRReqSend: Session timer is not required

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/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

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/ccsip_offer_ans_handle_sent_sdp:

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/SIP/Info/ccsip_offer_ans_process_event:

Mar 13 13:28:34.173: //605621/BD0B65A3AC16/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

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/ccsip_offer_ans_is_invite_offer_valid:  - 1

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/ccsip_offer_ans_common_offer_sent_hdlr:

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/ccsip_iwf_handle_network_event:

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/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_SENT_SDP

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/sip_iwf_def_ed_sent_sdp_offer_hdlr:

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT

Mar 13 13:28:34.177: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured

SIP: (605621) Group (a= group line) attribute, level 65535 instance 1 not found.

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/sipSPISendInvite: Associated container=0x3246BBBC to Invite

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Transport/sipSPITransportSendMessage: msg=0x32332364, addr=192.168.2.252, port=5060, sentBy_port=0, local_addr=192.168.2.253, is_req=1, transport=1, switch=0, callBack=0x251CB50C

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

Mar 13 13:28:34.177: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:192.168.2.252, rport:5060 with laddr:192.168.2.253

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x32332364

Mar 13 13:28:34.177: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x32332364, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, connId=4 for UDP

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE

Mar 13 13:28:34.177: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued.

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/State/sipSPIChangeState: 0x2B82D200 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_SENT_INVITE, SUBSTATE_NONE)

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 605621) to the VOIP RTP library

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 192.168.2.253

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info

    laddr = 192.168.2.253, lport = 31324, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE

    src_callid = 605621, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY

    media_ip_addr =  - , vrf tableid = 0 media_addr_type = 1

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/sipSPICreateRtpSession: sess: 320F7004 do_rtcp:0

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Media/sipSPICreateRtpSession: stun is disabled

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/State/sipSPIChangeStreamState: Stream (callid =  605621)  State changed from (STREAM_ADDING) to (STREAM_ACTIVE)

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Info/sipSPIUpdateCallEntry:

Call 605621 set InfoType to SPEECH

Mar 13 13:28:34.177: //605621/BD0B65A3AC16/SIP/Msg/ccsipDisplayMsg:

Sent:

INVITE sip:400@192.168.2.252:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK622049

From: <192.168.2.253>;tag=363A57A0-341

To: <400>

Date: Wed, 13 Mar 2013 13:28:34 GMT

Call-ID: BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE:  1800

Cisco-Guid: 3171640739-2333610466-2887180718-3613864289

User-Agent: Cisco-SIPGateway/IOS-12.x

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Max-Forwards: 70

Timestamp: 1363181314

Contact: <192.168.2.253:5060>

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 249

v=0

o=CiscoSystemsSIP-GW-UserAgent 970 3013 IN IP4 192.168.2.253

s=SIP Call

c=IN IP4 192.168.2.253

t=0 0

m=audio 31324 RTP/AVP 0 101

c=IN IP4 192.168.2.253

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

Mar 13 13:28:34.837: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [192.168.2.252]:32777, local_address:[192.168.2.253]

Mar 13 13:28:34.837: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT

SIP: (605621) Group (a= group line) attribute, level 65535 instance 1 not found.

Mar 13 13:28:34.837: //605621/BD0B65A3AC16/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat

Mar 13 13:28:34.837: //605621/BD0B65A3AC16/SIP/Info/sipSPISendInvite: Associated container=0x3246BBBC to Invite

Mar 13 13:28:34.837: //605621/BD0B65A3AC16/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer

Mar 13 13:28:34.837: //605621/BD0B65A3AC16/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE

Mar 13 13:28:34.837: //605621/BD0B65A3AC16/SIP/Transport/sipSPITransportSendMessage: msg=0x32332364, addr=192.168.2.252, port=5060, sentBy_port=0, local_addr=192.168.2.253, is_req=1, transport=1, switch=0, callBack=0x0

Mar 13 13:28:34.837: //605621/BD0B65A3AC16/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

Mar 13 13:28:34.837: //605621/BD0B65A3AC16/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

Mar 13 13:28:34.837: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:192.168.2.252, rport:5060 with laddr:192.168.2.253

Mar 13 13:28:34.837: //605621/BD0B65A3AC16/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x32332364

Mar 13 13:28:34.837: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x32332364, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, connId=4 for UDP

Mar 13 13:28:34.837: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1

Mar 13 13:28:34.837: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0

Mar 13 13:28:34.837: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog

Mar 13 13:28:34.841: //605621/BD0B65A3AC16/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK622049

To: <400>

From: <192.168.2.253>;tag=363A57A0-341

Call-ID: BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

CSeq: 101 INVITE

Content-Length: 0

Timestamp: 1363181314

Mar 13 13:28:34.841: //605621/BD0B65A3AC16/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX

Mar 13 13:28:34.841: //605621/BD0B65A3AC16/SIP/State/sipSPIChangeState: 0x2B82D200 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE)  to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)

Mar 13 13:28:34.841: //605621/BD0B65A3AC16/SIP/Msg/ccsipDisplayMsg:

Sent:

INVITE sip:400@192.168.2.252:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK622049

From: <192.168.2.253>;tag=363A57A0-341

To: <400>

Date: Wed, 13 Mar 2013 13:28:34 GMT

Call-ID: BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE:  1800

Cisco-Guid: 3171640739-2333610466-2887180718-3613864289

User-Agent: Cisco-SIPGateway/IOS-12.x

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Max-Forwards: 70

Timestamp: 1363181314

Contact: <192.168.2.253:5060>

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 249

v=0

o=CiscoSystemsSIP-GW-UserAgent 970 3013 IN IP4 192.168.2.253

s=SIP Call

c=IN IP4 192.168.2.253

t=0 0

m=audio 31324 RTP/AVP 0 101

c=IN IP4 192.168.2.253

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [192.168.2.252]:32777, local_address:[192.168.2.253]

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [192.168.2.252]:32777, local_address:[192.168.2.253]

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 486 Busy Here

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK622049

To: <400>;tag=dsf8096cb3

From: <192.168.2.253>;tag=363A57A0-341

Call-ID: BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

CSeq: 101 INVITE

Content-Length: 0

Allow: INVITE, BYE, CANCEL, ACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO

Cisco-Gcid: BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/ccsip_offer_ans_handle_call_active:

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/ccsip_offer_ans_process_event:

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/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

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/ccsip_offer_ans_process_event: O/A - cnfsm ret 2

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/sipSPISendAck: Associated container=0x3246906C to Ack

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipSPITransportSendMessage: msg=0x32332364, addr=192.168.2.252, port=5060, sentBy_port=0, local_addr=192.168.2.253, is_req=1, transport=1, switch=0, callBack=0x0

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:192.168.2.252, rport:5060 with laddr:192.168.2.253

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x32332364

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x32332364, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, connId=4 for UDP

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/act_recdproc_new_message_response: Received a 4/5/6xx message with StatusCode: 486

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[605621], src[4]

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(17) for outgoing call

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/State/sipSPIChangeState: 0x2B82D200 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)  to (STATE_DISCONNECTING, SUBSTATE_NONE)

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 486 Busy Here

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK622049

To: <400>;tag=dsf8096cb3

From: <192.168.2.253>;tag=363A57A0-341

Call-ID: BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

CSeq: 101 INVITE

Content-Length: 0

Allow: INVITE, BYE, CANCEL, ACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO

Cisco-Gcid: BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/ccsip_offer_ans_handle_call_active:

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/ccsip_offer_ans_process_event:

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/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

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/ccsip_offer_ans_process_event: O/A - cnfsm ret 2

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/sipSPISendAck: Associated container=0x3246B954 to Ack

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipSPITransportSendMessage: msg=0x2C35CFC0, addr=192.168.2.252, port=5060, sentBy_port=0, local_addr=192.168.2.253, is_req=1, transport=1, switch=0, callBack=0x251CD180

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:192.168.2.252, rport:5060 with laddr:192.168.2.253

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2C35CFC0

Mar 13 13:28:34.845: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2C35CFC0, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, connId=4 for UDP

Mar 13 13:28:34.845: //605621/BD0B65A3AC16/SIP/Info/sentErrorResponseCallClose: Sent Error Response since the Gw is Shutdown

Mar 13 13:28:34.849: //605621/BD0B65A3AC16/SIP/Info/sipSPIIcpifUpdate: CallState: 2 Playout: 0 DiscTime:90979385 ConnTime 0

Mar 13 13:28:34.849: //605621/BD0B65A3AC16/SIP/Media/sipSPIDestroyRtpSession: stream:324B4280

Mar 13 13:28:34.849: //605621/BD0B65A3AC16/SIP/State/sipSPIChangeState: 0x2B82D200 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DEAD, SUBSTATE_NONE)

Mar 13 13:28:34.849: //605621/BD0B65A3AC16/SIP/Call/sipSPICallInfo:

The Call Setup Information is:

Call Control Block (CCB) : 0x2B82D200

State of The Call        : STATE_DEAD

TCP Sockets Used         : NO

Calling Number           :

Called Number            : 400

Source IP Address (Sig  ): 192.168.2.253

Destn SIP Req Addr:Port  : 192.168.2.252:5060

Destn SIP Resp Addr:Port : 192.168.2.252:5060

Destination Name         : 192.168.2.252

Mar 13 13:28:34.849: //605621/BD0B65A3AC16/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): 192.168.2.253

Source IP Port    (Media): 31324

Destn  IP Address (Media):  -

Destn  IP Port    (Media): 0

Orig Destn IP Address:Port (Media): [ - ]:0

Mar 13 13:28:34.849: //605621/BD0B65A3AC16/SIP/Call/sipSPICallInfo:

Disconnect Cause (CC)    : 17

Disconnect Cause (SIP)   : 486

Mar 13 13:28:34.849: //605621/BD0B65A3AC16/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 93DB5

Mar 13 13:28:34.849: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

ACK sip:400@192.168.2.252:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK622049

From: <192.168.2.253>;tag=363A57A0-341

To: <400>;tag=dsf8096cb3

Date: Wed, 13 Mar 2013 13:28:34 GMT

Call-ID: BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: telephone-event

Content-Length: 0

Mar 13 13:28:34.849: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

ACK sip:400@192.168.2.252:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK622049

From: <192.168.2.253>;tag=363A57A0-341

To: <400>;tag=dsf8096cb3

Date: Wed, 13 Mar 2013 13:28:34 GMT

Call-ID: BD0C9DF3-8B1811E2-AC1BE1AE-D7673161@192.168.2.253

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: telephone-event

Content-Length: 0

Mar 13 13:28:44.169: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x2B85FB70) with key=[16971] to table

Mar 13 13:28:44.169: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:

Mar 13 13:28:44.169: //-1/000000000000/SIP/Info/ccsip_iwf_init: 

Mar 13 13:28:44.169: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:

Mar 13 13:28:44.169: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization:  Entry...

Mar 13 13:28:44.169: //605623/000000000000/SIP/State/sipSPIChangeState: 0x2B85FB70 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_call_setup_request: Set Protocol information

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 176

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:44.169: //605623/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

Mar 13 13:28:44.169: //605623/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/is_mode_sip_sip_md:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/is_mode_sip_sip_ed:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/is_mode_sip_h32x_in_set_mode:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/is_mode_sip_h323_in_set_mode:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM

Mar 13 13:28:44.169: //605623/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_default_early_dialog_container

Mar 13 13:28:44.169: //605623/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:44.169: //605623/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_FLOW_MODE

Mar 13 13:28:44.169: //605623/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/is_fa2ft_md_flow_mode_transition:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:

Mar 13 13:28:44.169: //605623/000000000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2

Mar 13 13:28:44.173: //605623/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS

Mar 13 13:28:44.173: //605623/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200

Mar 13 13:28:44.173: //605623/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200

Mar 13 13:28:44.173: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 192.168.2.252 target_port : 5060

Mar 13 13:28:44.173: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/ccsip_call_setup_request: Incrementing call counter to [1] in dial-peer [2001]

Mar 13 13:28:44.173: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 93DB7 to table

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec    bytes: 0

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIGetCallConfig: Media forking disabled

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIGetCallConfig: Checking Video Type Rate=-1 video_codec_allowed=1F

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=605622

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIGetCallConfig: Media forking disabled

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/preprocessSetup:

This is a not a SIGO Call -, could be DM call

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:44.173: //605623/C30146A3AC1C/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_INIT_CALL_SETUP

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 192.168.2.253

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 13:28:44.173: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 17716 for stream 1

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101

Mar 13 13:28:44.173: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20

Mar 13 13:28:44.173: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Media/sipSPIProcessRtpSessions: No active streams.

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIValidateGtd: Signal Forward disabled

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.2.253

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x2B85FB70 key=C3027EF3-8B1811E2-AC21E1AE-D7673161@192.168.2.253

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = C3027EF3-8B1811E2-AC21E1AE-D7673161@192.168.2.253

Mar 13 13:28:44.173: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 192.168.2.252,Port 5060, Transport 1, SentBy Port 5060

Mar 13 13:28:44.173: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT

Mar 13 13:28:44.173: //605623/C30146A3AC1C/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:3246BB64

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/Session-Timer/sipSTSLSRReqSend: Session timer is not required

Mar 13 13:28:44.177: //605623/C30146A3AC1C/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

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/ccsip_offer_ans_handle_sent_sdp:

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/ccsip_offer_ans_process_event:

Mar 13 13:28:44.177: //605623/C30146A3AC1C/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

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/ccsip_offer_ans_is_invite_offer_valid:  - 1

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/ccsip_offer_ans_common_offer_sent_hdlr:

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/ccsip_iwf_handle_network_event:

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/ccsip_iwf_process_event: 

Mar 13 13:28:44.177: //605623/C30146A3AC1C/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_SENT_SDP

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/sip_iwf_def_ed_sent_sdp_offer_hdlr:

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT

Mar 13 13:28:44.177: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured

SIP: (605623) Group (a= group line) attribute, level 65535 instance 1 not found.

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/sipSPISendInvite: Associated container=0x3246BB64 to Invite

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Transport/sipSPITransportSendMessage: msg=0x2B075EA4, addr=192.168.2.252, port=5060, sentBy_port=0, local_addr=192.168.2.253, is_req=1, transport=1, switch=0, callBack=0x251CB50C

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

Mar 13 13:28:44.177: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:192.168.2.252, rport:5060 with laddr:192.168.2.253

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2B075EA4

Mar 13 13:28:44.177: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2B075EA4, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, connId=4 for UDP

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE

Mar 13 13:28:44.177: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued.

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/State/sipSPIChangeState: 0x2B85FB70 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_SENT_INVITE, SUBSTATE_NONE)

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 605623) to the VOIP RTP library

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 192.168.2.253

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info

    laddr = 192.168.2.253, lport = 17716, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE

    src_callid = 605623, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY

    media_ip_addr =  - , vrf tableid = 0 media_addr_type = 1

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/sipSPICreateRtpSession: sess: 32334D84 do_rtcp:0

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Media/sipSPICreateRtpSession: stun is disabled

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/State/sipSPIChangeStreamState: Stream (callid =  605623)  State changed from (STREAM_ADDING) to (STREAM_ACTIVE)

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Info/sipSPIUpdateCallEntry:

Call 605623 set InfoType to SPEECH

Mar 13 13:28:44.177: //605623/C30146A3AC1C/SIP/Msg/ccsipDisplayMsg:

Sent:

INVITE sip:400@192.168.2.252:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK6313FD

From: <192.168.2.253>;tag=363A7EB0-1B29

To: <400>

Date: Wed, 13 Mar 2013 13:28:44 GMT

Call-ID: C3027EF3-8B1811E2-AC21E1AE-D7673161@192.168.2.253

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE:  1800

Cisco-Guid: 3271640739-2333610466-2887573934-3613864289

User-Agent: Cisco-SIPGateway/IOS-12.x

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Max-Forwards: 70

Timestamp: 1363181324

Contact: <192.168.2.253:5060>

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 250

v=0

o=CiscoSystemsSIP-GW-UserAgent 2032 4614 IN IP4 192.168.2.253

s=SIP Call

c=IN IP4 192.168.2.253

t=0 0

m=audio 17716 RTP/AVP 0 101

c=IN IP4 192.168.2.253

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

Mar 13 13:28:44.273: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [192.168.2.252]:32777, local_address:[192.168.2.253]

Mar 13 13:28:44.273: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1

Mar 13 13:28:44.273: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0

Mar 13 13:28:44.273: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog

Mar 13 13:28:44.273: //605623/C30146A3AC1C/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK6313FD

To: <400>

From: <192.168.2.253>;tag=363A7EB0-1B29

Call-ID: C3027EF3-8B1811E2-AC21E1AE-D7673161@192.168.2.253

CSeq: 101 INVITE

Content-Length: 0

Timestamp: 1363181324

Mar 13 13:28:44.273: //605623/C30146A3AC1C/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX

Mar 13 13:28:44.273: //605623/C30146A3AC1C/SIP/State/sipSPIChangeState: 0x2B85FB70 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE)  to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)

Mar 13 13:28:44.273: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [192.168.2.252]:32777, local_address:[192.168.2.253]

Mar 13 13:28:44.273: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1

Mar 13 13:28:44.273: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0

Mar 13 13:28:44.273: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog

Mar 13 13:28:44.273: //605623/C30146A3AC1C/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 486 Busy Here

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK6313FD

To: <400>;tag=ds813893c6

From: <192.168.2.253>;tag=363A7EB0-1B29

Call-ID: C3027EF3-8B1811E2-AC21E1AE-D7673161@192.168.2.253

CSeq: 101 INVITE

Content-Length: 0

Allow: INVITE, BYE, CANCEL, ACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO

Cisco-Gcid: C3027EF3-8B1811E2-AC21E1AE-D7673161@192.168.2.253

Mar 13 13:28:44.273: //605623/C30146A3AC1C/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX

Mar 13 13:28:44.273: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container

Mar 13 13:28:44.273: //605623/C30146A3AC1C/SIP/Info/ccsip_offer_ans_handle_call_active:

Mar 13 13:28:44.273: //605623/C30146A3AC1C/SIP/Info/ccsip_offer_ans_process_event:

Mar 13 13:28:44.273: //605623/C30146A3AC1C/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

Mar 13 13:28:44.273: //605623/C30146A3AC1C/SIP/Info/ccsip_offer_ans_process_event: O/A - cnfsm ret 2

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/sipSPISendAck: Associated container=0x3246AD4C to Ack

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Transport/sipSPITransportSendMessage: msg=0x2C35CFC0, addr=192.168.2.252, port=5060, sentBy_port=0, local_addr=192.168.2.253, is_req=1, transport=1, switch=0, callBack=0x0

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

Mar 13 13:28:44.277: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:192.168.2.252, rport:5060 with laddr:192.168.2.253

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2C35CFC0

Mar 13 13:28:44.277: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2C35CFC0, addr=192.168.2.252, port=5060, local_addr=192.168.2.253, connId=4 for UDP

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/act_recdproc_new_message_response: Received a 4/5/6xx message with StatusCode: 486

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[605623], src[4]

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(17) for outgoing call

Mar 13 13:28:44.277: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container

Mar 13 13:28:44.277: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/State/sipSPIChangeState: 0x2B85FB70 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)  to (STATE_DISCONNECTING, SUBSTATE_NONE)

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/ccsip_call_statistics: Requesting stats for callid=605623

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/ccsip_call_statistics: Stats request failed for callid=605623, dstCallID=-1, rc=-7

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured

Mar 13 13:28:44.277: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT

Mar 13 13:28:44.277: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 8

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT

Mar 13 13:28:44.277: //605623/C30146A3AC1C/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

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/sipSPIIcpifUpdate: CallState: 2 Playout: 0 DiscTime:90980328 ConnTime 0

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Media/sipSPIDestroyRtpSession: stream:324B6634

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/State/sipSPIChangeState: 0x2B85FB70 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DEAD, SUBSTATE_NONE)

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Call/sipSPICallInfo:

The Call Setup Information is:

Call Control Block (CCB) : 0x2B85FB70

State of The Call        : STATE_DEAD

TCP Sockets Used         : NO

Calling Number           :

Called Number            : 400

Source IP Address (Sig  ): 192.168.2.253

Destn SIP Req Addr:Port  : 192.168.2.252:5060

Destn SIP Resp Addr:Port : 192.168.2.252:5060

Destination Name         : 192.168.2.252

Mar 13 13:28:44.277: //605623/C30146A3AC1C/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): 192.168.2.253

Source IP Port    (Media): 17716

Destn  IP Address (Media):  -

Destn  IP Port    (Media): 0

Orig Destn IP Address:Port (Media): [ - ]:0

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Call/sipSPICallInfo:

Disconnect Cause (CC)    : 17

Disconnect Cause (SIP)   : 486

Mar 13 13:28:44.277: //605623/C30146A3AC1C/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 93DB7

Mar 13 13:28:44.277: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

ACK sip:400@192.168.2.252:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.253:5060;branch=z9hG4bK6313FD

From: <192.168.2.253>;tag=363A7EB0-1B29

To: <400>;tag=ds813893c6

Date: Wed, 13 Mar 2013 13:28:44 GMT

Call-ID: C3027EF3-8B1811E2-AC21E1AE-D7673161@192.168.2.253

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: telephone-event

Content-Length: 0

Mar 13 13:28:51.221: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[16969] removed.

Mar 13 13:28:51.221: //605619/B41A9423AC10/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.

Mar 13 13:28:51.221: //605619/B41A9423AC10/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x2B83DFD0 key=B41C689B-8B1811E2-AC15E1AE-D7673161@192.168.2.253

Mar 13 13:28:51.221: //605619/B41A9423AC10/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd

Mar 13 13:28:51.221: //605619/B41A9423AC10/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed

Mar 13 13:28:51.221: //605619/B41A9423AC10/SIP/Info/ccsip_offer_ans_delete:

Mar 13 13:28:51.221: //605619/B41A9423AC10/SIP/Info/ccsip_iwf_delete: 

Mar 13 13:28:51.221: //605619/B41A9423AC10/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 2B83DFD0

15356304-sfxisr01-confg.zip
0 Helpful

chrysostomos1980
Level 5
Level 5
In response to borhen bouallagui

‎03-13-2013 05:44 AM

I am not sure if the cue(AA) is function properly

can you post the config related for cue into VG ?

The voicemail is working?

Do you have the proper licenses for AA?

And final can i have the output of the status of cue?

Example:

CME# service-module integrated-Service-Engine 0/0 status

Regards

chrysostomos

Please rate all useful posts Regards Chrysostomos ""The Most Successful People Are Those Who Are Good At Plan B""
0 Helpful

borhen bouallagui
Level 1
Level 1
In response to chrysostomos1980

‎03-13-2013 05:52 AM

Generating configuration:

clock timezone Europe/Paris

hostname CabinetAmous

line console

system language preferred "fr_FR"

software download server url "ftp://127.0.0.1/ftp" credentials hidden "6u/dKTN/hsEuSAEfw40XlF2eFHnZfyUTSd8ZZNgd+Y9J3xlk2B35j0nfGWTYHfmPSd8ZZNgd+Y9J3xlk2B35j0nfGWTYHfmPSd8ZZNgd+Y9J3xlk2B35j0nfGWTYHfmP"

site name local

site-hostname 192.168.2.253

web credentials hidden "NwZxwNIdNpdYcprqAUCCvknfGWTYHfmPSd8ZZNgd+Y9J3xlk2B35j0nfGWTYHfmPSd8ZZNgd+Y9J3xlk2B35j0nfGWTYHfmPSd8ZZNgd+Y9J3xlk2B35j0nfGWTYHfmP"

end site

license agent max-sessions 9

privilege ViewRealTimeReports create

privilege manage-passwords create

privilege ManagePublicList create

privilege local-broadcast create

privilege manage-users create

privilege broadcast create

privilege vm-imap create

privilege ManagePrompts create

privilege ViewPrivateList create

privilege ViewHistoricalReports create

groupname Broadcasters create

username sfx11 create

username sfx13 create

username sfx7 create

username sfx19 create

username sfx15 create

username sfx18 create

username sfx17 create

username sfx12 create

username SFXSC create

username sfx16 create

username sfx4 create

username SFX create

username sfx3 create

username sfx5 create

username sfx10 create

username sfx6 create

username sfx9 create

username sfx8 create

username sfx14 create

username sfx2 create

username Admin create

privilege ViewRealTimeReports description "Privilege to view realtime reports"

privilege manage-passwords description "Privilege to reset user passwords"

privilege ManagePublicList description "Privilege to manage public lists"

privilege local-broadcast description "Privilege to send local broadcast messages"

privilege manage-users description "Privilege to create, modify, and delete users and groups"

privilege broadcast description "Privilege to send local or remote broadcast messages"

privilege vm-imap description "Privilege to manage personal voicemail via IMAP client"

privilege ManagePrompts description "Privilege to create, modify, or delete system prompts"

privilege ViewPrivateList description "Privilege to view private list"

privilege ViewHistoricalReports description "Privilege to view historical reports"

privilege ViewRealTimeReports operation report.realtime

privilege manage-passwords operation user.password

privilege manage-passwords operation user.pin

privilege manage-passwords operation system.debug

privilege ManagePublicList operation voicemail.lists.public

privilege ManagePublicList operation system.debug

privilege local-broadcast operation broadcast.local

privilege local-broadcast operation system.debug

privilege manage-users operation user.notification

privilege manage-users operation user.password

privilege manage-users operation user.remote

privilege manage-users operation user.configuration

privilege manage-users operation group.configuration

privilege manage-users operation user.pin

privilege manage-users operation system.debug

privilege manage-users operation user.mailbox

privilege broadcast operation broadcast.remote

privilege broadcast operation broadcast.local

privilege broadcast operation system.debug

privilege vm-imap operation voicemail.imap.user

privilege ManagePrompts operation system.debug

privilege ManagePrompts operation prompt.modify

privilege ViewPrivateList operation voicemail.lists.private.view

privilege ViewHistoricalReports operation report.historical.view

groupname Administrators member Admin

groupname Broadcasters privilege broadcast

username sfx11 phonenumber "203"

username sfx13 phonenumber "250"

username sfx7 phonenumber "111"

username sfx19 phonenumber "332"

username sfx15 phonenumber "133"

username sfx18 phonenumber "350"

username sfx17 phonenumber "112"

username sfx12 phonenumber "204"

username SFXSC phonenumber "110"

username sfx16 phonenumber "132"

username sfx4 phonenumber "105"

username SFX phonenumber "108"

username sfx3 phonenumber "103"

username sfx5 phonenumber "107"

username sfx10 phonenumber "202"

username sfx6 phonenumber "109"

username sfx9 phonenumber "201"

username sfx8 phonenumber "200"

username sfx14 phonenumber "130"

username sfx2 phonenumber "101"

restriction msg-notification create

restriction msg-notification min-digits 1

restriction msg-notification max-digits 30

restriction msg-notification dial-string preference 1 pattern * allowed

backup server url "ftp://127.0.0.1/ftp" credentials hidden "EWlTygcMhYmjazXhE/VNXHCkplVV4KjescbDaLa4fl4WLSPFvv1rWUnfGWTYHfmPSd8ZZNgd+Y9J3xlk2B35j0nfGWTYHfmPSd8ZZNgd+Y9J3xlk2B35j0nfGWTYHfmP"

calendar biz-schedule systemschedule

open day 1 from 00:00 to 24:00

open day 2 from 00:00 to 24:00

open day 3 from 00:00 to 24:00

open day 4 from 00:00 to 24:00

open day 5 from 00:00 to 24:00

open day 6 from 00:00 to 24:00

open day 7 from 00:00 to 24:00

end schedule

ccn application autoattendant aa

description "autoattendant"

enabled

maxsessions 4

script "aa.aef"

parameter "dialByExtnAnytime" "false"

parameter "busOpenPrompt" "AABusinessOpen.wav"

parameter "dialByExtnAnytimeInputLength" "3"

parameter "operExtn" "100"

parameter "welcomePrompt" "AAWelcome.wav"

parameter "disconnectAfterMenu" "true"

parameter "dialByFirstName" "true"

parameter "busClosedPrompt" "AABusinessClosed.wav"

parameter "allowExternalTransfers" "true"

parameter "holidayPrompt" "AAHolidayPrompt.wav"

parameter "businessSchedule" "systemschedule"

parameter "MaxRetry" "1"

end application

ccn application ciscomwiapplication aa

description "ciscomwiapplication"

enabled

maxsessions 10

script "setmwi.aef"

parameter "CallControlGroupID" "0"

parameter "strMWI_OFF_DN" "A801"

parameter "strMWI_ON_DN" "A800"

end application

ccn application msgnotification aa

description "msgnotification"

enabled

maxsessions 10

script "msgnotify.aef"

parameter "logoutUri" "http://localhost/voicemail/vxmlscripts/mbxLogout.jsp"

parameter "DelayBeforeSendDTMF" "1"

end application

ccn application promptmgmt aa

description "promptmgmt"

enabled

maxsessions 1

script "promptmgmt.aef"

parameter "appManagementScript" ""

end application

ccn application voicemail aa

description "voicemail"

enabled

maxsessions 10

script "voicebrowser.aef"

parameter "uri" "http://localhost/voicemail/vxmlscripts/login.vxml"

parameter "logoutUri" "http://localhost/voicemail/vxmlscripts/mbxLogout.jsp"

end application

ccn engine

end engine

ccn reporting historical

database local

description "se-192-168-2-252"

end reporting

ccn subsystem email

end subsystem

ccn subsystem fax

end subsystem

ccn subsystem sip

gateway address "192.168.2.253"

end subsystem

ccn trigger http urlname msgnotifytrg

application "msgnotification"

enabled

maxsessions 2

end trigger

ccn trigger http urlname mwiapp

application "ciscomwiapplication"

enabled

maxsessions 1

end trigger

ccn trigger sip phonenumber 400

application "autoattendant"

enabled

locale "fr_FR"

maxsessions 4

end trigger

ccn trigger sip phonenumber 800

application "voicemail"

enabled

locale "fr_FR"

maxsessions 5

end trigger

service phone-authentication

end phone-authentication

service voiceview

enable

end voiceview

voicemail notification enable

voicemail notification allow-login

voicemail broadcast mwi

voicemail callerid

voicemail default mailboxsize 3600

voicemail broadcast recording time 300

voicemail default messagesize 240

voicemail notification restriction msg-notification

voicemail live-record beep duration 0

voicemail mailbox owner "SFX" size 3600

end mailbox

voicemail mailbox owner "sfx10" size 3600

end mailbox

voicemail mailbox owner "sfx11" size 3600

end mailbox

voicemail mailbox owner "sfx12" size 3600

end mailbox

voicemail mailbox owner "sfx13" size 3600

end mailbox

end

for the license i juste intall a license for 5 mailbox for test

0 Helpful

borhen bouallagui
Level 1
Level 1
In response to borhen bouallagui

‎03-13-2013 05:57 AM

SFXISR01#service-module ism 0/0 status

Service Module is Cisco ISM0/0

Service Module supports session via TTY line 67

Service Module is in Steady state

Service Module heartbeat-reset is enabled

Getting status from the Service Module, please wait..

Cisco Unity Express 8.6.4

CUE Running on ISM

Module resource information:

  CPU Frequency: 1068 MHz

  Memory Size: 493 MB

  Disk 0 Size: 4110 MB

No install/uninstall in progress

No local partition

0 Helpful

chrysostomos1980
Level 5
Level 5
In response to borhen bouallagui

‎03-13-2013 07:25 AM

Hi

Unfortunattely all looks ok

Did you reload the cue?

service-module ism 0/0 reload

Please rate all useful posts Regards Chrysostomos ""The Most Successful People Are Those Who Are Good At Plan B""
0 Helpful

borhen bouallagui
Level 1
Level 1
In response to chrysostomos1980

‎03-13-2013 07:49 AM

yes i reload and the same problem

0 Helpful

chrysostomos1980
Level 5
Level 5
In response to borhen bouallagui

‎03-13-2013 07:55 AM

Hi

Two questions

1)  the voicemail is working?

2) did you configure aa script?

Regards

chrysostomos

Please rate all useful posts Regards Chrysostomos ""The Most Successful People Are Those Who Are Good At Plan B""
0 Helpful

borhen bouallagui
Level 1
Level 1
In response to chrysostomos1980

‎03-13-2013 08:20 AM

the script configured but the voicemail notworking

0 Helpful

chrysostomos1980
Level 5
Level 5
In response to borhen bouallagui

‎03-13-2013 12:26 PM

So the cue is not running properly

Did you perform the initial installation of cue?or you have done it from cli

May you have to reinstall the cue application if the initial installation has been done seccesfully

Regards

cc

Please rate all useful posts Regards Chrysostomos ""The Most Successful People Are Those Who Are Good At Plan B""

borhen bouallagui
Level 1
Level 1
In response to chrysostomos1980

‎03-13-2013 12:39 PM

the initial installation of CUE from Cli

0 Helpful

chrysostomos1980
Level 5
Level 5
In response to borhen bouallagui

‎03-13-2013 12:45 PM

send pls the

show ccn subsystem sip

Also r you sure that you have all the appropriates licenses for aa and voicemail?

Please rate all useful posts Regards Chrysostomos ""The Most Successful People Are Those Who Are Good At Plan B""
Customers Also Viewed These Support Documents