codec mismatch

Joli Martinez · ‎03-20-2020

I have a call from Freeswitch to an IAD going back to our SBC. The dial-peer is matched but I am having some sort of codec mismatch and not sure how to fix it. I have forced the IAD to use g711 and it still fails. I have added g711ulaw and a law as well as g722 and it still fails. Not sure where to go from here.

voice class codec 711

codec preference 1 g711ulaw

codec preference 2 g711alaw

codec preference 3 g722-64

dial-peer voice 103 voip

description 10 Digit Dialing

translation-profile outgoing E164

destination-pattern [2-9].........

progress_ind setup enable 3

voice-class codec 711

session protocol sipv2

session target dns:sip.oursbc.com

session transport udp

dtmf-relay rtp-nte

no fax-relay sg3-to-g3

ip qos dscp cs5 media

ip qos dscp cs4 signaling

no vad

!

INVITE sip:3334445555@10.41.100.5 SIP/2.0

Via: SIP/2.0/UDP 10.41.100.2:5080;rport;branch=z9hG4bKetaj2eS80yNQa

Max-Forwards: 69

From: "LCS" <sip:FreeSWITCH@10.41.100.5>;tag=eKvvvN70gKveQ

To: <sip:3334445555@10.41.100.5>

Call-ID: 231b0a87-e56b-1238-0aa6-005056873da3

CSeq: 17807162 INVITE

Contact: <sip:gw+17530ce2-2aba-4f3e-8a16-dc3295c03661@10.41.100.2:5080;transport=udp;gw=17530ce2-2aba-4f3e-8a16-dc3295c03661>

User-Agent: FreeSWITCH

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

Supported: timer, path, replaces

Allow-Events: talk, hold, conference, refer

Content-Type: application/sdp

Content-Disposition: session

Content-Length: 293

X-FS-Support: update_display,send_info

Remote-Party-ID: "LCS" <sip:7778889999@10.41.100.5>;party=calling;screen=yes;privacy=off

v=0

o=FreeSWITCH 1584696828 1584696829 IN IP4 10.41.100.2

s=FreeSWITCH

c=IN IP4 10.41.100.2

t=0 0

m=audio 24384 RTP/AVP 9 0 8 101

a=rtpmap:9 G722/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

*Mar 8 21:28:55.148: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog

*Mar 8 21:28:55.148: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x65BA5380) with key=[31] to table

*Mar 8 21:28:55.148: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.41.100.2,Port 5080, Transport 1, SentBy Port 5080

*Mar 8 21:28:55.148: //-1/53D840FF8066/SIP/State/sipSPIChangeState: 0x65BA5380 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)

*Mar 8 21:28:55.148: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.41.100.2,Port 5080, Transport 1, SentBy Port 5060

*Mar 8 21:28:55.148: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT

*Mar 8 21:28:55.148: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.41.100.2,Port 5080, Transport 1, SentBy Port 5080

*Mar 8 21:28:55.152: //-1/xxxxxxxxxxxx/SIP/Info/httpish_msg_lock: Increment msg (0x657AF4E8) counter,

current msg->refCount = 2

*Mar 8 21:28:55.152: //-1/xxxxxxxxxxxx/SIP/Error/httpish_msg_free: Freeing NULL pointer!

*Mar 8 21:28:55.152: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAssignCcbLastRequest: Current ccb(0x65BA5380)->last_request = 0x657AF4E8, refCount = 2

*Mar 8 21:28:55.152: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckIpip: VOIP dialpeer (peer=0x640D45FC) found for sip_user: 3334445555

*Mar 8 21:28:55.152: //-1/53D840FF8066/SIP/Info/sipSPISetInfoFromRpid: Received current remote name: LCS, current remote number: 7778889999

*Mar 8 21:28:55.152: //-1/53D840FF8066/SIP/Info/sipSPISetInfoFromRpid: Received ;screen=yes ;privacy=off -> Setting Octet3A 0x81, extended_privacy 0x00

*Mar 8 21:28:55.152: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container

*Mar 8 21:28:55.152: //-1/53D840FF8066/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x65BA5380 key=231b0a87-e56b-1238-0aa6-005056873da33334445555

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on carrier id

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 3334445555

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on destination pattern: 7778889999

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name LCS, number 7778889999, Calling oct3 0x00, oct_3a 0x81, Called number 3334445555

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/sipSPIGetCallConfig: Peer tag 103 matched for incoming call

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 711

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Media/sipSPICopyPeerDataToCCB: Firewall traversal is not enabled

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/sipSPIGetCallConfig: xcoder high-density disabled

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/sipSPIGetCallConfig: Flow Mode set to FLOW_THROUGH

*Mar 8 21:28:55.156: //-1/53D840FF8066/SIP/Info/sipSPIGetCallConfig: Media forking disabled

*Mar 8 21:28:55.160: //-1/53D840FF8066/SIP/Info/sipSPIContinueNewMsgInvite: Calling name LCS, number 7778889999, Calling oct3 0x00, oct_3a 0x81, ext_priv 0x00, Called number 3334445555, oct3 0x00

*Mar 8 21:28:55.160: //-1/53D840FF8066/SIP/Info/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE

*Mar 8 21:28:55.160: //-1/53D840FF8066/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0

*Mar 8 21:28:55.160: //19/53D840FF8066/SIP/Info/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container

*Mar 8 21:28:55.160: //19/53D840FF8066/SIP/Info/sipSPIProcessDiversionHeader: No diversion headers recvd from app container

*Mar 8 21:28:55.160: //19/53D840FF8066/SIP/Info/sipSPIProcessReplacesHeader: No replaces hdr found

*Mar 8 21:28:55.160: //19/53D840FF8066/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1

SIP: (19) Attribute mid, level 1 instance 1 not found.

*Mar 8 21:28:55.160: //19/53D840FF8066/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.41.100.5

*Mar 8 21:28:55.160: //19/53D840FF8066/SIP/Media/sipSPISelectCodecVersion: g722-64 flavor of G722 codec will be used

*Mar 8 21:28:55.160: //19/53D840FF8066/SIP/Info/sipSPIDoAudioNegotiation: Codec (g722-64) Negotiation Successful on Static Payload for m-line 1

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sipSPIDoPtimeNegotiation: One ptime attribute found - value:20

*Mar 8 21:28:55.164: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g722-64 ptime :20, codecbytes: 160

*Mar 8 21:28:55.164: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g722-64 codecbytes :160, ptime: 20

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Media/sipSPIDoPtimeNegotiation: Offered ptime:20, Negotiated ptime:20 Negotiated codec bytes: 160 for codec g722-64

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(101) could not be reserved.

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sipSPIDoDTMFRelayNegotiation: RTP-NTE DTMF relay option

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events.

*Mar 8 21:28:55.164: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1

payload_type=9, codec_bytes=160, codec=g722-64, dtmf_relay=rtp-nte

stream_type=voice+dtmf (1), dest_ip_address=10.41.100.2, dest_port=24384

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING)

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Media/sipSPIUpdCallWithSdpInfo:

Preferred Codec : g711ulaw, bytes :160

Preferred DTMF relay : rtp-nte

Preferred NTE payload : 101

Early Media : No

Delayed Media : No

Bridge Done : No

New Media : No

DSP DNLD Reqd : No

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.41.100.5

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sipSPI_ipip_report_media_to_peer:

callId 19 peer 0 flags 0x201 state STATE_IDLE

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:

CallID 19, sdp 0x65EFE538 channels 0x65BA6640

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/copy_channels:

callId 19 size 0 ptr 0x657B2670)

*Mar 8 21:28:55.164: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:

Hndl ptype 9 mline 1

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Media/sipSPISelectCodecVersion: g722-64 flavor of G722 codec will be used

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g722-64

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/codec_found:

Codec to be matched: 37

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 37

*Mar 8 21:28:55.168: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g722-64 codecbytes :160, ptime: 20

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation done: stream->negotiated_ptime=20,stream->negotiated_codec_bytes=160, coverted ptime=20 stream->mline_index=1, media_ndx=1

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:

failed to update call entry

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:

Adding codec 37 ptype 9 time 20, bytes 160 as channel 0 mline 1 ss 0 10.41.100.2:24384

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:

Hndl ptype 0 mline 1

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711ulaw

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/codec_found:

Codec to be matched: 5

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 5

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=20, media_ndx=1

*Mar 8 21:28:55.168: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g711ulaw ptime :20, codecbytes: 160

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:

failed to update call entry

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:

Adding codec 5 ptype 0 time 20, bytes 160 as channel 1 mline 1 ss 0 10.41.100.2:24384

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:

Hndl ptype 8 mline 1

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711alaw

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/codec_found:

Codec to be matched: 6

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 6

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=20, media_ndx=1

*Mar 8 21:28:55.168: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g711alaw ptime :20, codecbytes: 160

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:

failed to update call entry

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:

Adding codec 6 ptype 8 time 20, bytes 160 as channel 2 mline 1 ss 0 10.41.100.2:24384

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:

Hndl ptype 101 mline 1

*Mar 8 21:28:55.168: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: setting ipip_caps DTMF to RFC2833: callid = 19, dtmf = 6

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 37

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 5

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 6

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = -1

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPI_ipip_report_media_to_peer:

callId 19 flags 0x300 state STATE_IDLE

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPI_ipip_report_media_to_peer:

Report initial call media

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPI_ipip_report_media_to_peer: ccb->flags 0xC, ccb->pld.flags_ipip 0x201

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/copy_channels:

callId 19 size 696 ptr 0x65EE0014)

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPI_ipip_report_media_to_peer:

CCSIP: Unable to report channel ind

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/ccsip_update_srtp_caps: 5054: Posting Remote SRTP caps to other callleg.

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPI_ipip_report_media_to_peer: do cc_api_caps_ind()

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Media/sipSPIUpdCallWithSdpInfo:

Stream type : voice+dtmf

Media line : 1

State : STREAM_ADDING (2)

Stream address type : 1

Callid : -1

Negotiated Codec : g722-64, bytes :160

Nego. Codec payload : 9 (tx), 9 (rx)

Negotiated DTMF relay : rtp-nte

Negotiated NTE payload : 101 (tx), 101 (rx)

Negotiated CN payload : 0

Media Srce Addr/Port : [10.41.100.5]:0

Media Dest Addr/Port : [10.41.100.2]:24384

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPIHandleInviteMedia:

Negotiated Codec : g722-64, bytes :160

Preferred Codec : g711ulaw, bytes :160

Preferred DTMF relay 1 : 6

Preferred DTMF relay 2 : 0

Negotiated DTMF relay : 6

Preferred and Negotiated NTE payloads: 101 101

Preferred and Negotiated NSE payloads: 100 0

Preferred and Negotiated Modem Relay: 0 0

Preferred and Negotiated Modem Relay GwXid: 1 0

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPIDoQoSNegotiationWithMediaLine: Entry

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPIDoQoSNegotiationWithMediaLine: QOS negotiation for mline_index 1

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPIDoStreamQoSNegotiation: Best effort

*Mar 8 21:28:55.172: //19/53D840FF8066/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active

*Mar 8 21:28:55.176: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 16700 for stream 1

*Mar 8 21:28:55.176: //19/53D840FF8066/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Reserving rtp port for stream 1, src_port=16700

*Mar 8 21:28:55.176: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1

*Mar 8 21:28:55.176: //19/53D840FF8066/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Setting stream 1 portnum to 16700

*Mar 8 21:28:55.176: //19/53D840FF8066/SIP/Info/sipSPIUpdateSrcSdpVariablePart:

SIP update src sdp, negoitated codec 37, payload type 9

*Mar 8 21:28:55.176: //19/53D840FF8066/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Negotiated method of dtmf relayand pyld: 6 101

*Mar 8 21:28:55.176: //19/53D840FF8066/SIP/Info/sipSPIAddBillingInfoToCcb: sipCallId for billing records = 231b0a87-e56b-1238-0aa6-005056873da3

*Mar 8 21:28:55.176: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentCPA: No CPA found in inbound container

*Mar 8 21:28:55.176: //19/53D840FF8066/SIP/Info/sipSPIProcessCPA: No x-cisco-cpa content found

*Mar 8 21:28:55.176: //19/53D840FF8066/SIP/Info/sipSPI_ipip_store_channel_info: Store channelInfo in CallInfo

*Mar 8 21:28:55.176: //19/53D840FF8066/SIP/Info/sipSPI_ipip_store_channel_info: dtmf negotiation done, storing negotiated dtmf = 6,

*Mar 8 21:28:55.176: //19/53D840FF8066/SIP/Info/sipSPIShrlCall: Check peer: 103 for Shared-Line call, callid: 19

*Mar 8 21:28:55.176: //19/53D840FF8066/SIP/Info/ccsip_set_bearer_capability:

Bearer Capability: Speech (0x00)

*Mar 8 21:28:55.176: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container

*Mar 8 21:28:55.176: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container

*Mar 8 21:28:55.176: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateNewRawMsg: No Data to form The Raw Message

*Mar 8 21:28:55.180: //19/53D840FF8066/SIP/Info/sipSPIContinueNewMsgInvite: ccsip_api_call_setup_ind returned: SIP_SUCCESS

*Mar 8 21:28:55.180: //19/53D840FF8066/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 13 to table

*Mar 8 21:28:55.180: //-1/xxxxxxxxxxxx/SIP/Info/httpish_msg_create: created msg=0x65E5A6A0 with refCount = 1

*Mar 8 21:28:55.180: //19/53D840FF8066/SIP/Transport/sipSPITransportSendMessage: msg=0x65E5A6A0, addr=10.41.100.2, port=5080, sentBy_port=5080, is_req=0, transport=1, switch=0, callBack=0x00000000

*Mar 8 21:28:55.180: //19/53D840FF8066/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

*Mar 8 21:28:55.180: //19/53D840FF8066/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

*Mar 8 21:28:55.180: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x65E5A6A0, addr=10.41.100.2, port=5080, connId=0 for UDP

*Mar 8 21:28:55.180: //19/53D840FF8066/SIP/State/sipSPIChangeState: 0x65BA5380 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_RECD_INVITE, SUBSTATE_NONE)

*Mar 8 21:28:55.180: //19/53D840FF8066/SIP/Info/sipSPIProcessContactInfo: Previous Hop 10.41.100.2:5080

*Mar 8 21:28:55.184: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING

*Mar 8 21:28:55.188: //19/53D840FF8066/SIP/Info/ccsip_call_statistics: Stats are not supported for IPIP call.

*Mar 8 21:28:55.188: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT

*Mar 8 21:28:55.188: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 3

*Mar 8 21:28:55.188: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7

*Mar 8 21:28:55.188: //19/53D840FF8066/SIP/Info/act_recdinvite_disconnect: Performing disconnect

*Mar 8 21:28:55.192: //-1/xxxxxxxxxxxx/SIP/Info/httpish_msg_create: created msg=0x64DC9174 with refCount = 1

*Mar 8 21:28:55.192: //19/53D840FF8066/SIP/Info/sipSPIUaddCcbToUASRespTable: ****Adding to UAS Response table.

*Mar 8 21:28:55.192: //19/53D840FF8066/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x65BA5380 key=231b0a87-e56b-1238-0aa6-005056873da328A88EC8-DDC

*Mar 8 21:28:55.192: //19/53D840FF8066/SIP/Info/sipSPISendInviteResponse: Associated container=0x65D9D688 to Invite Response 404

*Mar 8 21:28:55.192: //19/53D840FF8066/SIP/Transport/sipSPITransportSendMessage: msg=0x64DC9174, addr=10.41.100.2, port=5080, sentBy_port=5080, is_req=0, transport=1, switch=0, callBack=0x61B0A9D4

*Mar 8 21:28:55.192: //19/53D840FF8066/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

*Mar 8 21:28:55.192: //19/53D840FF8066/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

*Mar 8 21:28:55.192: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x64DC9174, addr=10.41.100.2, port=5080, connId=0 for UDP

*Mar 8 21:28:55.192: //19/53D840FF8066/SIP/Info/sentErrResDisconnecting: Sent an 3456XX Error Response

*Mar 8 21:28:55.192: //19/53D840FF8066/SIP/State/sipSPIChangeState: 0x65BA5380 : State change from (STATE_RECD_INVITE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)

*Mar 8 21:28:55.196: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent: