Cisco Support Community
cancel
Showing results for 
Search instead for 
Did you mean: 
Announcements

Welcome to Cisco Support Community. We would love to have your feedback.

For an introduction to the new site, click here. If you'd prefer to explore, try our test area to get started. And see here for current known issues.

New Member

Calls drop when answered after pressing mobility button

Hi,

I have a BE6K 9.1.2. A 2921 gateway with SIP trunk from CUCM to 2921 and T1 to provider.

All works well but when I use Mobility to extend a call to my cell, the call drops once the cell phone is answered. I've pasted a ccsip trace. 

Any help would be appriciated.


BCBHQVG1(conf-voi-serv)#
Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x22F6792C, addr=172.16.56.10, port=43514, local_addr=172.16.56.20, connid=3, transport=TCP
Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Info/sipHolderGetConnInstance: Return existing connection for port 43514 connId 3
Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:94413243417@172.16.56.20:5060 SIP/2.0
Via: SIP/2.0/TCP 172.16.56.10:5060;branch=z9hG4bK164234b721f
From: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;tag=276~87c0ab4f-26e3-46a1-8286-a35df32454d0-19204700
To: <sip:94413243417@172.16.56.20>
Date: Fri, 18 Jul 2014 19:09:15 GMT
Call-ID: 12a0500-3c9170db-107-a3810ac@172.16.56.10
Supported: timer,resource-priority,replaces
Min-SE:  1800
User-Agent: Cisco-CUCM9.1
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
CSeq: 101 INVITE
Expires: 180
Allow-Events: presence, kpml
Supported: X-cisco-srtp-fallback
Supported: Geolocation
Call-Info: <sip:172.16.56.10:5060>;method="NOTIFY;Event=telephone-event;Duration=500"
Cisco-Guid: 0019531008-0000065536-0000000034-0171446444
Session-Expires:  1800
Diversion: "Support Consultant 890" <sip:4412395678@172.16.56.10>;reason=follow-me;privacy=off;screen=yes
P-Asserted-Identity: "Andrew Harte 669" <sip:4412992869@172.16.56.10>
Remote-Party-ID: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;party=calling;screen=yes;privacy=off
Contact: <sip:4412992869@172.16.56.10:5060;transport=tcp>;isFocus
Max-Forwards: 70
Content-Length: 0


Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x234F6978) with key=[3616] to table
Jul 18 19:09:15.853: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:
Jul 18 19:09:15.853: //-1/000000000000/SIP/Info/ccsip_iwf_init:
Jul 18 19:09:15.853: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:
Jul 18 19:09:15.853: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization:  Entry...
Jul 18 19:09:15.853: //-1/000000000000/SIP/Info/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 172.16.56.10,Port 43514, Transport 2, SentBy Port 5060
Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Info/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 172.16.56.20 for SIP
Jul 18 19:09:15.853: //-1/000000000000/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 172.16.56.20
Jul 18 19:09:15.853: //-1/012A05000000/SIP/State/sipSPIChangeState: 0x234F6978 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)
Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 172.16.56.10,Port 43514, Transport 2, SentBy Port 5060
Jul 18 19:09:15.853: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone AST to SIP default timezone = GMT
Jul 18 19:09:15.857: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 172.16.56.10,Port 43514, Transport 2, SentBy Port 5060
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIUpdateIdentity: MF: PAI url is present & make a copy..
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIUpdateIdentity: SIP->PSTN: Setting Calling Name to Andrew Harte 669
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIUpdateIdentity: PAI  genUrl is prresent..
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIUpdateIdentity: SIP->PSTN: Setting Calling Number to 4412992869
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIUpdateIdentity: PAI host = 172.16.56.10
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
Jul 18 19:09:15.857: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
Jul 18 19:09:15.857: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentCSTA: No CSTA found in inbound container
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x234F6978 key=12a0500-3c9170db-107-a3810ac@172.16.56.1094413243417
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/ccsipUpdateRedirectWithGtd:
Redirecting Information:
Number =   4412395678
Reason =   10
Counter =  -1
Presentation Indicator =  0
Type of Number =          0
Numbering Plan Id =       0
Screening Indicator =     1
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on carrier id
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 94413243417
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on destination pattern: 4412992869
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name Andrew Harte 669, number 4412992869, Calling oct3 0x00, oct_3a 0x81, Called number 94413243417
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetViaHostInURLFormat: VIA URL:sip:172.16.56.10:5060, Host:172.16.56.10
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : 4412992869
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetFromCalledPartyId: P-Called-Party-ID header not found
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetPeerByCalledPartyId: P-Called-Party-ID not found or parse error
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetCallConfig: No match found for P-Called-Party-ID
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/ccsip_validate_and_update_calling_info: PAI/PPI not configuredi for this dial-peer(204), use RPID/FROM header data
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/ccsip_ipip_media_forking_copy_prev_best_rpid: MF: This is best rpid..
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with RPID header data
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPISetInfoFromRpid: Received current remote name: Andrew Harte 669, current remote number: 4412992869
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPISetInfoFromRpid: Received ;screen=yes ;privacy=off -> Setting Octet3A 0x81, extended_privacy 0x00
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetCallConfig: Peer tag 204 matched for incoming call
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
Jul 18 19:09:15.857: //-1/xxxxxxxxxxxx/SIP/Info/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 172.16.56.20 for SIP
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 172.16.56.20
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=0
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Error/ccsip_ipip_media_forking_update_preferred_codec:
 MF: Not a Forked SIP leg..
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetCallConfig: Incoming: No defer BYE for last
                              call stats
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetCallConfig: Media forking disabled
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/ccsip_ipip_media_forking_anchor_leg_config: MF: en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer has no media class recorder.
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIMFChangeState: MF: Prev state = 0 & New state = -1
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/ccsip_ipip_media_forking_anchor_leg_reset: MF: Anchor leg config reset done...
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/ccsipInitDSCPPolicyInfo: No DSCP Profile configured, No RPH 2 DSCP Mapping and DSCP policing
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIGetCallConfig: Initilise the DSCP policy
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/populate_vcc_data: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIContinueNewMsgInvite: Calling name Andrew Harte 669, number 4412992869, Calling oct3 0x00, oct_3a 0x81, ext_priv 0x00, Called number 94413243417, oct3 0x00
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIValidateRequestUri: Not Enabled
Jul 18 19:09:15.857: //-1/012A05000000/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
Jul 18 19:09:15.857: //5959/012A05000000/SIP/Info/sipSPI_ipip_IsSDPPassthruEnabled:  - 0
Jul 18 19:09:15.857: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
Jul 18 19:09:15.857: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: dir:2, method:102, resp_code:0, container:2259E18C
Jul 18 19:09:15.857: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLExtractSessionExpiresHdr:
Session-Expires value: 1800 refresher: none
Jul 18 19:09:15.857: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLExtractMinSEHdr: Min-SE Duration: 1800
Jul 18 19:09:15.857: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLAdjustConfigIntervals: Changing config min-se:360 to 1800
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLAdjustConfigIntervals: Changing config se:360 to 1800
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLGetInternalSREvent: E_STSL_INITIAL_SR_REQ
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLInitialSRReqPeerEventGen: sending received session expires to the peer leg
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLPrintTDContainer: Peer-Event: E_STSL_PASS_ST_PARAMS, SE Value:1800, SE Refresher:none, Min-SE Value:1800, flags:2001
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLMain:
        SE: 1800;refresher:none peer refresher:none, flags:2001, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
        Configured SE:1800, Configured Min-SE:1800
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIProcessNotifyCallInfoHeader: Callinfo parsed - Destination url = <sip:172.16.56.10:5060>, duration = 500

Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIProcessReplacesHeader: No replaces hdr found
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentSDP: No SDP found in inbound container
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIDoQoSNegotiationWithNoMediaLine: Local Precondition: 1, Remote Precondition: 1
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sip_iwf_def_copy_sdp_to_channelInfo:
CallID 5959, sdp 0x3E0198D0 channels 0x234F80D4
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sip_iwf_def_copy_sdp_to_channelInfo: bwcac no sdp
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: calculating max bw from preffered codecs (local offer)
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Error/sipSPICheckAnatGroupPresent:
 NULL sdp
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (excluding pak overhead) from preffered codecs: codec g711ulaw        bw 64000        index 0
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (including pak overhead) from preffered codecs: codec g711ulaw        bw 80000
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcDialPeerBandwidth: bwcac dial-peer audio bw 80000 bps video bw 0 bpsfax bw 0 bps
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted BW Option 0 flow mode flow-through
audio bw 80000 bps video bw 0 bps fax bw 0 bps total bw 80000 bps  accounted bw 0 bps
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIBwCacUpdateInterfaceBw: bwcac acquiring interface GigabitEthernet0/0 bw 80
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (initial offer) accounted bw set to 80000
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 204
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIBwCacIsInterfaceBwAvailable: bwcac interface bw threshold not configured
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 80000 bps
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Delayed media case...creating new stream
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Info/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 172.16.56.20 for SIP
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.16.56.20
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 21064 for stream 1
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Reserving rtp port for stream 1, src_port=21064
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIAddBillingInfoToCcb: sipCallId for billing records = 12a0500-3c9170db-107-a3810ac@172.16.56.10
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentCPA: No CPA found in inbound container
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIProcessCPA: No x-cisco-cpa content found
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/ccsip_api_call_setup_ind: Headers from INVITE added to callInfo container
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_ExtractPassthruCopyListHdrsFromSipContainer: Unsupported header passthru is not configured and no headers are present in hdr_hash_queue
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_IsContentPassthruEnabled:  - 0
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_ExtractPassthruContentFromSipContainer: Passthru Content Not Enabled
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/ccsip_api_call_setup_ind: Unable to add unsupp headers to container
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/ccsip_api_call_setup_ind: Set Protocol information
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_store_channel_info: Store channelInfo in CallInfo
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_store_channel_info: negotiated dtmf not available, using dialpeer config dtmf = 6
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_store_config_info: Setting mid_call_config_info = 0x0 for callid = 5959
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Media/sipSPIDisplayStreamInfo:
          Stream type            : voice+dtmf
          Media line             : 1
          State                  : STREAM_IDLE (1)
          Stream address type    : 1
          Callid                 : -1
          Peer Callid            : -1
          RTP/SRTP Negotiated     : 0
          Negotiated Codec       : No Codec   , bytes :0
          Nego. Codec payload    : 255 (tx), 255 (rx)
          Negotiated DTMF relay  : inband-voice
          Negotiated NTE payload : 0 (tx), 0 (rx)
          Negotiated CN payload  : 0
          Media Srce Addr/Port   : [172.16.56.20]:21064
          Media Dest Addr/Port   : [ - ]:0

Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_calculate_channel_count: Number of streams to report = 0
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_store_channel_info: Setting CHANNEL_COUNT = 0 for callid 5959
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIShrlCall: Check peer: 204 for Shared-Line call, callid: 5959
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/ccsip_set_bearer_capability:
   Bearer Capability: Speech (0x00)
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateNewRawMsg: No Data to form The Raw Message

Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIContinueNewMsgInvite: ccsip_api_call_setup_ind returned: SIP_SUCCESS_DELAYED_MEDIA
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 1747 to table
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIUpdateCallEntry:
Call 5959 set InfoType to SPEECH
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:100, container:225A2D84
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLValidateSessRefreshMsg: Ignoring 1xx response for session timer processing
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIPresendProcessing: Presend Processing called for 3 event
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer:  Could not get any elements from TD Container
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer:  Could not get any elements from TD Container
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPISendInviteResponse: Associated container=0x225A2D84 to Invite Response 100
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: msg=0x3A0B7134, addr=172.16.56.10, port=43514, sentBy_port=5060, local_addr=172.16.56.20, is_req=0, transport=2, switch=0, callBack=0x0
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Jul 18 19:09:15.861: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x3A0B7134, addr=172.16.56.10, port=43514, local_addr=172.16.56.20, connId=3 for TCP
Jul 18 19:09:15.861: //5959/012A05000000/SIP/State/sipSPIChangeState: 0x234F6978 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_RECD_INVITE, SUBSTATE_NONE)
Jul 18 19:09:15.861: //5959/012A05000000/SIP/Info/sipSPIProcessContactInfo: Previous Hop 172.16.56.10:5060
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 172.16.56.10:5060;branch=z9hG4bK164234b721f
From: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;tag=276~87c0ab4f-26e3-46a1-8286-a35df32454d0-19204700
To: <sip:94413243417@172.16.56.20>
Date: Fri, 18 Jul 2014 19:09:15 GMT
Call-ID: 12a0500-3c9170db-107-a3810ac@172.16.56.10
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.2.4.M6a
Content-Length: 0


Jul 18 19:09:15.865: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 183)
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_event_handler: Current mode is SIP-TDM
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_iwf_handle_peer_event:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 183
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_iwf_process_event:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_MODE
Jul 18 19:09:15.865: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_mode_sip_sip_md_snr:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_mode_sip_sip_ed_snr:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_mode_sip_sip_md:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_mode_sip_sip_ed:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_mode_sip_h32x_in_set_mode:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_mode_sip_h323_in_set_mode:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_mode_sip_sccp_in_set_mode:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_mode_sip_sccp_in_set_mode:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM
Jul 18 19:09:15.865: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_default_early_dialog_container
Jul 18 19:09:15.865: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_iwf_map_cc_return_code: Mapping SIP_IWF_SUCCESS
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_iwf_handle_peer_event:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_iwf_process_event:
Jul 18 19:09:15.865: //5959/012A05000000/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
Jul 18 19:09:15.865: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_fa2ft_md_flow_mode_transition:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_fa2ft_flow_mode_transition:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/is_fa2ft_flow_mode_transition:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
Jul 18 19:09:15.865: //5959/012A05000000/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
Jul 18 19:09:15.869: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 3
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/sip_gw_video_handle_alert: Video caps are not detected in the caps posted by peer leg
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_call_alert: CCSIP_CALL_ALERT : KPML_CONFIGURED[FALSE] collect_digits[94413243417] calledNumber[94413243417] display_name[] display_num[]
Jul 18 19:09:16.337: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_ALERTING
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_query_codec_info: codec = 1
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_bridge: confID = 70, srcCallID = 5959, dstCallID = 5960
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/sipSPIUupdateCcCallIds: Old src/dest ccCallids: -1/-1, new src/dest ccCallids: 5959/5960
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/sipSPIUupdateCcCallIds: Old streamcallid=-1, new streamcallid=5959
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-TDM
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_iwf_handle_peer_event:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 183
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_iwf_process_event:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_MODE
Jul 18 19:09:16.337: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/is_mode_sip_sip_md_snr:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/is_mode_sip_sip_ed_snr:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/is_mode_sip_sip_md:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/is_mode_sip_sip_ed:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/is_mode_sip_h32x_in_set_mode:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/is_mode_sip_h323_in_set_mode:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/is_mode_sip_sccp_in_set_mode:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/is_mode_sip_sccp_in_set_mode:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM
Jul 18 19:09:16.337: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_default_early_dialog_container
Jul 18 19:09:16.337: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_iwf_handle_generic_event:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_iwf_process_event:
Jul 18 19:09:16.337: //5959/012A05000000/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_CALL_BRIDGE
Jul 18 19:09:16.337: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_bridge: xcoder_attached = 0, xmitFunc = 0x34B885A4, ccb xmitFunc = 0x34B885A4
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_bridge: ccsip_bridge: Delayed Media case
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_iwf_handle_peer_event:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 28
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/ccsip_iwf_process_event:
Jul 18 19:09:16.337: //5959/012A05000000/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_PEER_CAPS
Jul 18 19:09:16.337: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/is_mode_sip_sccp_do_video:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/sip_iwf_def_peer_caps_ind_hdlr:
Jul 18 19:09:16.337: //5959/012A05000000/SIP/Info/sip_iwf_def_process_caps_ind: Negotiation not done yet:: Load DSP with preferred codec : g711ulaw, Bytes=160, payload: 0
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 UPDATE
Contact: <sip:94413243417@172.16.56.20:5060;transport=tcp>
Min-SE:  1800
Remote-Party-ID: <sip:3243417@172.16.56.20>;party=called;screen=no;privacy=off
Content-Length: 0


Jul 18 19:09:17.065: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 18 19:09:17.065: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x22F6792C
Jul 18 19:09:17.065: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x22F6792C, addr=172.16.56.10, port=5060, local_addr=172.16.56.20, connid=4, transport=TCP
Jul 18 19:09:17.065: //-1/xxxxxxxxxxxx/SIP/Info/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Jul 18 19:09:17.065: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 18 19:09:17.065: //5959/012A05000000/SIP/Info/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK8041070 from via branch list
Jul 18 19:09:17.065: //5959/012A05000000/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x234F6978
Jul 18 19:09:17.065: //5959/012A05000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.56.20:5060;branch=z9hG4bK8041070
From: <sip:94413243417@172.16.56.20>;tag=D7F0F538-12D7
To: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;tag=276~87c0ab4f-26e3-46a1-8286-a35df32454d0-19204700
Date: Fri, 18 Jul 2014 19:09:16 GMT
Call-ID: 12a0500-3c9170db-107-a3810ac@172.16.56.10
CSeq: 101 UPDATE
Allow-Events: presence, kpml
Supported: X-cisco-srtp-fallback
Supported: Geolocation
P-Asserted-Identity: "Andrew Harte 669" <sip:4412992869@172.16.56.10>
Remote-Party-ID: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;party=called;screen=yes;privacy=off
Contact: <sip:3243417@172.16.56.10:5060;transport=tcp>;isFocus
Content-Length: 0


Jul 18 19:09:17.065: //5959/012A05000000/SIP/Info/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX
Jul 18 19:09:17.069: //5959/012A05000000/SIP/Info/ccsip_ipip_media_forking_copy_prev_best_rpid: MF: Delete the previous remote party id..
Jul 18 19:09:17.069: //5959/012A05000000/SIP/Info/ccsip_ipip_media_forking_copy_prev_best_rpid: MF: This is best rpid..
Jul 18 19:09:17.069: //5959/012A05000000/SIP/Info/sipSPISetInfoFromRpid: Received current remote name: Andrew Harte 669, current remote number: 4412992869
Jul 18 19:09:17.069: //5959/012A05000000/SIP/Info/sipSPISetInfoFromRpid: Received ;screen=yes ;privacy=off -> Setting Octet3A 0x81, extended_privacy 0x00
Jul 18 19:09:17.069: //5959/012A05000000/SIP/Info/sipSPIUpdateResponseReceived: Received resonse code : 200
Jul 18 19:09:17.069: //5959/012A05000000/SIP/Info/ccsip_api_response_answer: ccsip_api_response_answer.

Jul 18 19:09:17.069: //5959/012A05000000/SIP/Info/ccsip_api_response_answer:
RTP_LPBK == FALSE, checkpoint HA data

Jul 18 19:09:17.069: //5959/012A05000000/SIP/Info/ccsip_api_response_answer: Received a response to an UPDATE without SDP
Jul 18 19:09:17.069: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
Jul 18 19:09:17.069: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: dir:2, method:109, resp_code:200, container:225A00D4
Jul 18 19:09:17.069: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLValidateSessRefreshMsg: Ignoring early dialog update for session timer processing
Jul 18 19:09:17.069: //5959/012A05000000/SIP/Info/act_recdinvite_new_message_response: ccsip_api_response_answer return value: SIP_SUCCESS
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sip_gw_video_handle_alert: Video caps are not detected in the caps posted by peer leg
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/ccsip_call_alert: CCSIP_CALL_ALERT: app_diaplay_name[] app_display_num[3243417]
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/ccsip_call_alert: CCSIP_CALL_ALERT : KPML_CONFIGURED[FALSE] collect_digits[94413243417] calledNumber[94413243417] display_name[] display_num[3243417]
Jul 18 19:09:20.337: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_ALERTING
Jul 18 19:09:20.337: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 5
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIValidateGtd: Signal Forward disabled
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIAddCiscoGcid: Fatal Error in parsing CCB/Msg
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/preprocessAlertOrProgress: changing ALERT event to PROGRESS
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Error/sipSPI_ipip_set_history_info_header:
 Not SIP2SIP mode
SIP: (5959) Group (a= group line) attribute, level 65535 instance 1 not found.
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: calculating max bw from preffered codecs (local offer)
SIP: (5959) Group (a= group line) attribute, level 65535 instance 1 not found.
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (excluding pak overhead) from preffered codecs: codec g711ulaw        bw 64000        index 0
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: audio caps channel idx not found !!!!
Jul 18 19:09:20.337: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (including pak overhead) from preffered codecs: codec g711ulaw        bw 80000
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted BW Option 0 flow mode flow-through
audio bw 80000 bps video bw 0 bps fax bw 0 bps total bw 80000 bps  accounted bw 80000 bps
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIBwCacUpdateInterfaceBw: NOP (no interface change)
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (initial offer) accounted bw set to 80000
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 204
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 80000 bps
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:183, container:225A217C
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLValidateSessRefreshMsg: Ignoring 1xx response for session timer processing
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIPresendProcessing: Presend Processing called for 10 event
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer:  Could not get any elements from TD Container
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer:  Could not get any elements from TD Container
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/ccsip_offer_ans_handle_sent_sdp:
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/ccsip_offer_ans_process_event:
Jul 18 19:09:20.337: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_SENT, event:E_SIP_INVITE_RESP_SDP_SENT
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/ccsip_offer_ans_ignore_hdlr: S_SIP_EARLY_DIALOG_OFFER_SENT, E_SIP_INVITE_RESP_SDP_SENT
Jul 18 19:09:20.337: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: OA:next_state:CNFSM_NO_STATE_CHANGE
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Event/sipSPICreateRpid: Received Octet3A=0x00 -> Setting ;screen=no ;privacy=off
SIP: (5959) Group (a= group line) attribute, level 65535 instance 1 not found.
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPISendInviteResponse: Associated container=0x225A217C to Invite Response 183
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Transport/sipSPISendInviteResponse: Sending 183 Response to the Transport Layer
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: msg=0x3A0B7134, addr=172.16.56.10, port=43514, sentBy_port=5060, local_addr=172.16.56.20, is_req=0, transport=2, switch=0, callBack=0x347E4A24
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 18 19:09:20.337: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x22F6792C, addr=172.16.56.10, port=5060, local_addr=172.16.56.20, unregistering context=0x234F6978
Jul 18 19:09:20.337: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection: Purging context gcb=0x234F6978 from the connection=0x3D0CB060 context list
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Jul 18 19:09:20.337: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x3A0B7134, addr=172.16.56.10, port=43514, local_addr=172.16.56.20, connId=3 for TCP
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sentInviteResponse18x: Sent a 18x Response
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/ccsipDOInviteResponseSent:
ccsipDOInviteResponseSent: entered, ccb->flags == DELAYED_MEDIA, ccb->flags == EARLY_MEDIA

Jul 18 19:09:20.337: //5959/012A05000000/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_ACTIVE
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIAssignStreamCallId: Assigning streamid 5959 to stream 1
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIUpdateRtcpSession:
ccb->flags != LOOPBACK

Jul 18 19:09:20.337: //5959/012A05000000/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info
        laddr = 172.16.56.20, lport = 21064, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE
        src_callid = 5959, dest_callid = 5960, stream type = voice+dtmf, stream direction = SENDRECV
        media_ip_addr =  - , vrf tableid = 0 media_addr_type = 1        negotiated_bandwidth (kbps) = 0
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Media/sipSPIUpdateRtcpSession: RTP session already created - update
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:23E610C4
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIUpdateRtcpSession: Not able to Associate DSCP Profile with GCCB dscp_policy = 0x0, IS_SIPSPI_MODE_IN_SIP_SIP = 0 dscpPolicySeviceBlock = 0x0 , stream->qos_info = 0x0
Jul 18 19:09:20.337: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIUpdateRtcpSession: VQM: gccb=0x0, gccb->callId=0, ccb->ccCallID=5959
Jul 18 19:09:20.337: //5959/012A05000000/SIP/Info/sipSPIUpdateRtcpSession:
 DTMF inb/oob disabled
Jul 18 19:09:20.341: //5959/012A05000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 183 Session Progress
Via: SIP/2.0/TCP 172.16.56.10:5060;branch=z9hG4bK164234b721f
From: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;tag=276~87c0ab4f-26e3-46a1-8286-a35df32454d0-19204700
To: <sip:94413243417@172.16.56.20>;tag=D7F0F538-12D7
Date: Fri, 18 Jul 2014 19:09:15 GMT
Call-ID: 12a0500-3c9170db-107-a3810ac@172.16.56.10
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: <sip:3243417@172.16.56.20>;party=called;screen=no;privacy=off
Contact: <sip:94413243417@172.16.56.20:5060;transport=tcp>
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-15.2.4.M6a
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 247

v=0
o=CiscoSystemsSIP-GW-UserAgent 8571 1867 IN IP4 172.16.56.20
s=SIP Call
c=IN IP4 172.16.56.20
t=0 0
m=audio 21064 RTP/AVP 0 101
c=IN IP4 172.16.56.20
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

Jul 18 19:09:23.837: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_call_connect: CCSIP_CALL_CONNECT: ccb ptr 234F6978

Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/ccsip_iwf_handle_generic_event:
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/ccsip_iwf_process_event:
Jul 18 19:09:23.837: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP, event:E_SIP_IWF_EV_CALL_CONNECT
Jul 18 19:09:23.837: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sip_iwf_common_call_connect_hdlr:
Jul 18 19:09:23.837: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
Jul 18 19:09:23.837: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_call_connect: CCSIP_CALL_CONNECT: app_diaplay_name[] app_display_num[3243417]
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/ccsip_call_connect: CCSIP_CALL_CONNECT : KPML_CONFIGURED[FALSE] collect_digits[94413243417] calledNumber[94413243417] display_name[] display_num[3243417]
Jul 18 19:09:23.837: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_CONNECT
Jul 18 19:09:23.837: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_MEDIA_EVENT
Jul 18 19:09:23.837: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipAppMsgPlatformPreprocessor:
delayed_media:TRUE, recvd msg->type == SIPSPI_EV_CC_CALL_CONNECT, invoke preprocessConnect
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Error/sipSPI_ipip_set_history_info_header:
 Not SIP2SIP mode
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/preprocessConnect: Negotiation Not Yet done ! Delay Revert BW
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/preprocessConnect: Write sdp_info into msg_body
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIValidateGtd: Signal Forward disabled
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled
SIP: (5959) Group (a= group line) attribute, level 65535 instance 1 not found.
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: calculating max bw from preffered codecs (local offer)
SIP: (5959) Group (a= group line) attribute, level 65535 instance 1 not found.
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (excluding pak overhead) from preffered codecs: codec g711ulaw        bw 64000        index 0
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: audio caps channel idx not found !!!!
Jul 18 19:09:23.837: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (including pak overhead) from preffered codecs: codec g711ulaw        bw 80000
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted BW Option 0 flow mode flow-through
audio bw 80000 bps video bw 0 bps fax bw 0 bps total bw 80000 bps  accounted bw 80000 bps
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIBwCacUpdateInterfaceBw: NOP (no interface change)
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (initial offer) accounted bw set to 80000
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 204
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 80000 bps
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:200, container:225A00D4
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLSRRespSend: Session expires header is received in the request, starting the session timer
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLStartSessionTimer: Started Session Expiry Timer with duration:1768000
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLMain:
        SE: 1800;refresher:uas peer refresher:none, flags:1, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
        Configured SE:1800, Configured Min-SE:1800
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPIPresendProcessing: Presend Processing called for 8 event
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer:  Could not get any elements from TD Container
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer:  Could not get any elements from TD Container
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/ccsip_offer_ans_handle_sent_sdp:
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/ccsip_offer_ans_process_event:
Jul 18 19:09:23.837: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_SENT, event:E_SIP_INVITE_RESP_SDP_SENT
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Info/ccsip_offer_ans_ignore_hdlr: S_SIP_EARLY_DIALOG_OFFER_SENT, E_SIP_INVITE_RESP_SDP_SENT
Jul 18 19:09:23.837: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: OA:next_state:CNFSM_NO_STATE_CHANGE
Jul 18 19:09:23.837: //5959/012A05000000/SIP/Event/sipSPICreateRpid: Received Octet3A=0x00 -> Setting ;screen=no ;privacy=off
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Info/sipSPIAppAddCallInfoUI: callinfo UI update request for callid: 5959

Jul 18 19:09:23.841: //5959/012A05000000/SIP/Info/sipSPISendInviteResponse: Transaction active. Facilities will be queued.
SIP: (5959) Group (a= group line) attribute, level 65535 instance 1 not found.
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Info/sipSPISendInviteResponse: Associated container=0x225A00D4 to Invite Response 200
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Transport/sipSPISendInviteResponse: Sending 200OK Response to the Transport Layer
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: msg=0x3A0B7134, addr=172.16.56.10, port=43514, sentBy_port=5060, local_addr=172.16.56.20, is_req=0, transport=2, switch=0, callBack=0x347E4E44
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Jul 18 19:09:23.841: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x3A0B7134, addr=172.16.56.10, port=43514, local_addr=172.16.56.20, connId=3 for TCP
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Info/sentInviteResponse200: Sent 200Ok for Invite in state STATE_RECD_INVITE
Jul 18 19:09:23.841: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteResponse200: Transaction active. Facilities will be queued.
Jul 18 19:09:23.841: //5959/012A05000000/SIP/State/sipSPIChangeState: 0x234F6978 : State change from (STATE_RECD_INVITE, SUBSTATE_NONE)  to (STATE_SENT_SUCCESS, SUBSTATE_NONE)
Jul 18 19:09:23.841: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 29
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Info/Session-Timer/sipSTSLMain:
        SE: 1800;refresher:uas peer refresher:none, flags:1, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
        Configured SE:1800, Configured Min-SE:1800
Jul 18 19:09:23.841: //5959/012A05000000/SIP/Info/ccsip_buffer_media_event: Buffering event SIPSPI_EV_CC_MEDIA_EVENT - Purpose 1: FALSE

Jul 18 19:09:23.841: //5959/012A05000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.56.10:5060;branch=z9hG4bK164234b721f
From: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;tag=276~87c0ab4f-26e3-46a1-8286-a35df32454d0-19204700
To: <sip:94413243417@172.16.56.20>;tag=D7F0F538-12D7
Date: Fri, 18 Jul 2014 19:09:15 GMT
Call-ID: 12a0500-3c9170db-107-a3810ac@172.16.56.10
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: <sip:3243417@172.16.56.20>;party=called;screen=no;privacy=off
Contact: <sip:94413243417@172.16.56.20:5060;transport=tcp>
Supported: replaces
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-15.2.4.M6a
Require: timer
Session-Expires:  1800;refresher=uac
Supported: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 247

v=0
o=CiscoSystemsSIP-GW-UserAgent 8571 1867 IN IP4 172.16.56.20
s=SIP Call
c=IN IP4 172.16.56.20
t=0 0
m=audio 21064 RTP/AVP 0 101
c=IN IP4 172.16.56.20
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x22F6792C, addr=172.16.56.10, port=43514, local_addr=172.16.56.20, connid=3, transport=TCP
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Info/sipHolderGetConnInstance: Return existing connection for port 43514 connId 3
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:94413243417@172.16.56.20:5060;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 172.16.56.10:5060;branch=z9hG4bK165143b029e
From: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;tag=276~87c0ab4f-26e3-46a1-8286-a35df32454d0-19204700
To: <sip:94413243417@172.16.56.20>;tag=D7F0F538-12D7
Date: Fri, 18 Jul 2014 19:09:16 GMT
Call-ID: 12a0500-3c9170db-107-a3810ac@172.16.56.10
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: presence, kpml
Content-Length: 0


Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x234F6978
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.56.10,Port 43514, Transport 2, SentBy Port 5060
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone AST to SIP default timezone = GMT
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.56.10,Port 43514, Transport 2, SentBy Port 5060
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with FROM header data
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/act_sentsucc_new_message_request: Transaction Complete. Lock on Facilities released.
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/act_sentsucc_new_message_request: Media body not avail in ACK for delayed media call
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Error/sipSPIProcessAckMedia:
 Destination SDP Body unavailable
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/ccsip_set_cc_cause_for_spi_err: Categorized cause:96, category:96
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[5959], src[6]
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPI_ipip_ExtractPassthruCopyListHdrsFromSipContainer: Unsupported header passthru is not configured and no headers are present in hdr_hash_queue
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPI_ipip_IsContentPassthruEnabled:  - 0
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPI_ipip_ExtractPassthruContentFromSipContainer: Passthru Content Not Enabled
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/ccsip_api_request_answer: Could not add hdrs for ev:1
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/ccsip_api_request_answer: ACK handling retval: SIP_UNACCEPTABLE_MEDIA_ERR
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/act_sentsucc_new_message_request: ccsip_api_request_answer return val:SIP_UNACCEPTABLE_MEDIA_ERR
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(96) for incoming call
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQSIG:
 No Inbound Container Created !!!
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQ931:
 No Inbound Container Created !!!
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 28
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg..
Jul 18 19:09:24.469: //5959/012A05000000/SIP/State/sipSPIChangeState: 0x234F6978 : State change from (STATE_SENT_SUCCESS, SUBSTATE_NONE)  to (STATE_DISCONNECTING, SUBSTATE_NONE)
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/act_active_disconnect: act_active_disconnect: Disconnect now..
                              no defer BYE..
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPIPresendProcessing: Presend Processing called for 3 event
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer:  Could not get any elements from TD Container
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer:  Could not get any elements from TD Container
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sipSPISendBye: Associated container=0x2259EB84 to Bye
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Transport/sipSPISendBye: Sending BYE to the transport layer
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: msg=0x3A0B7134, addr=172.16.56.10, port=5060, sentBy_port=0, local_addr=172.16.56.20, is_req=1, transport=2, switch=0, callBack=0x347E51C8
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x22F6792C, addr=172.16.56.10, port=43514, local_addr=172.16.56.20, unregistering context=0x234F6978
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: Could not purge context gcb=0x234F6978 from the connection; gcb might be locked
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:172.16.56.10, rport:5060 with laddr:172.16.56.20

Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x234F6978 with connection=0x3D0CB060 context list
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x3A0B7134
Jul 18 19:09:24.469: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x3A0B7134, addr=172.16.56.10, port=5060, local_addr=172.16.56.20, connId=4 for TCP
Jul 18 19:09:24.469: //5959/012A05000000/SIP/Info/sentByeDisconnecting: Sent Bye Request, starting DisconnectTimer
Jul 18 19:09:24.469: //5959/012A05000000/SIP/State/sipSPIChangeState: 0x234F6978 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DISCONNECTING, SUBSTATE_NONE)
Jul 18 19:09:24.473: //5959/012A05000000/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 17
Jul 18 19:09:24.473: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_XCODER_RESET_STREAM
Jul 18 19:09:24.473: //5959/012A05000000/SIP/Info/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES*****
Jul 18 19:09:24.473: //5959/012A05000000/SIP/Info/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES*****
Jul 18 19:09:24.473: //5959/012A05000000/SIP/Info/sipSPIResetXcoder: Xcoder resource already cleaned up
Jul 18 19:09:24.473: //5959/012A05000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE
Jul 18 19:09:24.473: //5959/012A05000000/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:23E610C4
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:4412992869@172.16.56.10:5060;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 172.16.56.20:5060;branch=z9hG4bK80521B9
From: <sip:94413243417@172.16.56.20>;tag=D7F0F538-12D7
To: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;tag=276~87c0ab4f-26e3-46a1-8286-a35df32454d0-19204700
Date: Fri, 18 Jul 2014 19:09:24 GMT
Call-ID: 12a0500-3c9170db-107-a3810ac@172.16.56.10
User-Agent: Cisco-SIPGateway/IOS-15.2.4.M6a
Max-Forwards: 70
Timestamp: 1405710564
CSeq: 102 BYE
Reason: Q.850;cause=86
P-RTP-Stat: PS=0,OS=0,PR=0,OR=0,PL=0,JI=0,LA=0,DU=0
Content-Length: 0


Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x22F6792C, addr=172.16.56.10, port=43514, local_addr=172.16.56.20, connid=3, transport=TCP
Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Info/sipHolderGetConnInstance: Return existing connection for port 43514 connId 3
Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:94413243417@172.16.56.20:5060;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 172.16.56.10:5060;branch=z9hG4bK1663a9760e
From: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;tag=276~87c0ab4f-26e3-46a1-8286-a35df32454d0-19204700
To: <sip:94413243417@172.16.56.20>;tag=D7F0F538-12D7
Date: Fri, 18 Jul 2014 19:09:16 GMT
Call-ID: 12a0500-3c9170db-107-a3810ac@172.16.56.10
User-Agent: Cisco-CUCM9.1
Max-Forwards: 70
P-Asserted-Identity: "Andrew Harte 669" <sip:4412992869@172.16.56.10>
CSeq: 102 BYE
Reason: Q.850;cause=47
Content-Length: 0


Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x234F6978
Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.56.10,Port 43514, Transport 2, SentBy Port 5060
Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone AST to SIP default timezone = GMT
Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.56.10,Port 43514, Transport 2, SentBy Port 5060
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Info/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with FROM header data
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Info/sipSPIPresendProcessing: Presend Processing called for 3 event
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer:  Could not get any elements from TD Container
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer:  Could not get any elements from TD Container
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Info/sipSPISendByeResponse: Associated container=0x225A5A34 to Bye Response
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Transport/sipSPISendByeResponse: Sending BYE Response to the transport layer
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: msg=0x3A0B7134, addr=172.16.56.10, port=43514, sentBy_port=5060, local_addr=172.16.56.20, is_req=0, transport=2, switch=0, callBack=0x0
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x22F6792C, addr=172.16.56.10, port=5060, local_addr=172.16.56.20, unregistering context=0x234F6978
Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection: Purging context gcb=0x234F6978 from the connection=0x3D0CB060 context list
Jul 18 19:09:24.481: //5959/012A05000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Jul 18 19:09:24.481: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x3A0B7134, addr=172.16.56.10, port=43514, local_addr=172.16.56.20, connId=3 for TCP
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/ccsip_call_statistics: Requesting stats for callid=5959
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 28
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg..
Jul 18 19:09:24.485: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
Jul 18 19:09:24.485: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 8
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPIBwCacReleaseDialPeerBw: bwcac dial-peer bw 80 Kbps released for tag 204 active bw 0 Kbps
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPIBwCacReleaseInterfaceBw: bwcac releasing interface GigabitEthernet0/0 bw 80 Kbps
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPIBwCacReleaseAccountedBw: bwcac released accounted  bw 80000 bps
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/act_disconnecting_disconnect: Disconnect deferred, as stats request pending.
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.56.10:5060;branch=z9hG4bK1663a9760e
From: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;tag=276~87c0ab4f-26e3-46a1-8286-a35df32454d0-19204700
To: <sip:94413243417@172.16.56.20>;tag=D7F0F538-12D7
Date: Fri, 18 Jul 2014 19:09:24 GMT
Call-ID: 12a0500-3c9170db-107-a3810ac@172.16.56.10
Server: Cisco-SIPGateway/IOS-15.2.4.M6a
CSeq: 102 BYE
P-RTP-Stat: PS=0,OS=0,PR=0,OR=0,PL=0,JI=0,LA=0,DU=0
Content-Length: 0


Jul 18 19:09:24.485: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 18 19:09:24.485: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x22F6792C
Jul 18 19:09:24.485: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x22F6792C, addr=172.16.56.10, port=5060, local_addr=172.16.56.20, connid=4, transport=TCP
Jul 18 19:09:24.485: //-1/xxxxxxxxxxxx/SIP/Info/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Jul 18 19:09:24.485: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK80521B9 from via branch list
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x234F6978
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.56.20:5060;branch=z9hG4bK80521B9
From: <sip:94413243417@172.16.56.20>;tag=D7F0F538-12D7
To: "Andrew Harte 669" <sip:4412992869@172.16.56.10>;tag=276~87c0ab4f-26e3-46a1-8286-a35df32454d0-19204700
Date: Fri, 18 Jul 2014 19:09:24 GMT
Call-ID: 12a0500-3c9170db-107-a3810ac@172.16.56.10
CSeq: 102 BYE
Content-Length: 0


Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sact_disconnecting_new_message_response: [sact_disconnecting_new_message_response] Received Response Class [2] Method Code [103]

Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPIDeferCallClose: Not split dataplane, bail
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPIWaitForStatsBforeCallClose: Not split dataplane, bail
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPICallCloseAfterFinalStat:
sipSPICallCloseAfterFinalStat:
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Info/sipSPIIcpifUpdate: CallState: 4 Playout: 0 DiscTime:362290100 ConnTime 362290035
Jul 18 19:09:24.485: //5959/012A05000000/SIP/Media/sipSPIHandleDestroyRtpSession: stream:23E610C4
Jul 18 19:09:24.489: //5959/012A05000000/SIP/State/sipSPIChangeState: 0x234F6978 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DEAD, SUBSTATE_NONE)
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x0x234F6978
State of The Call        : STATE_DEAD
TCP Sockets Used         : YES
Calling Number           : 4412992869
Called Number            : 94413243417
Source IP Address (Sig  ): 172.16.56.20
Destn SIP Req Addr:Port  : 172.16.56.10:5060
Destn SIP Resp Addr:Port : 172.16.56.10:43514
Destination Name         : 172.16.56.10

Jul 18 19:09:24.489: //5959/012A05000000/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): 172.16.56.20
Source IP Port    (Media): 21064
Destn  IP Address (Media):  -
Destn  IP Port    (Media): 0
Orig Destn IP Address:Port (Media): [ - ]:0

Jul 18 19:09:24.489: //5959/012A05000000/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC)    : 86
Disconnect Cause (SIP)   : 200

Jul 18 19:09:24.489: //5959/012A05000000/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 1747
Jul 18 19:09:24.489: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[3616] removed.
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x234F6978 key=12a0500-3c9170db-107-a3810ac@172.16.56.1094413243417
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x234F6978 key=12a0500-3c9170db-107-a3810ac@172.16.56.10D7F0F538-12D7
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Jul 18 19:09:24.489: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x22F6792C, addr=172.16.56.10, port=43514, local_addr=172.16.56.20, unregistering context=0x234F6978
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Transport/sipSPITransportContextCleanup: Could not purge context gcb=0x234F6978 from the connection; gcb might be locked
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Info/ccsip_offer_ans_delete:
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Info/ccsip_iwf_delete:
Jul 18 19:09:24.489: //5959/012A05000000/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 0x234F6978
Jul 18 19:09:24.489: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[3616]
Jul 18 19:09:24.677: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_indicate_rt_packet_stats:
 CCB could not be found.

 

1 REPLY
New Member

Anyone got an ideas?

Anyone got any ideas?
281
Views
0
Helpful
1
Replies