sip/2.0 400 bad request

[Jonathan Forbes]

I am having trouble getting my 2801 to receive incomming calls.

When i bypass my firewall all works well. When i am behind the firewall it works great.

I am getting this error in me logs.

sip/2.0 400 bad request

I did open a ticket with the firewall vendor and they say the 2801 is trying to send the public IP and not the ip address that is on the 2801.

Any help would be great.  

[Abhay Singh Reyal]

Try disabling the SIP inspection and see what happens then. In case if does not work provide the call flow and debugs from the voice gateway.

HTH

Regards

Abhay

[Jonathan Forbes]

I am being told that the sip inspection is shut off on the firewall. 

Here is my ccsip call log

Router#
*Aug 4 14:52:21.244: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[191] removed.
*Aug 4 14:52:21.244: //26/47452CF28104/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.
*Aug 4 14:52:21.244: //26/47452CF28104/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x68B0BEA8 key=474AAAF2-785B11E7-810AC70A-1EA9078C@10.80.110.3
*Aug 4 14:52:21.244: //26/47452CF28104/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Aug 4 14:52:21.244: //26/47452CF28104/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
*Aug 4 14:52:21.244: //26/47452CF28104/SIP/Info/ccsip_offer_ans_delete:
*Aug 4 14:52:21.244: //26/47452CF28104/SIP/Info/ccsip_iwf_delete:
*Aug 4 14:52:21.244: //26/47452CF28104/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 68B0BEA8
*Aug 4 14:52:21.436: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [216.82.224.202]:5060, local_address:[ - ]
*Aug 4 14:52:21.436: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Aug 4 14:52:21.436: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
*Aug 4 14:52:21.436: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:+14026710461@68.96.19.74:5060;transport=udp SIP/2.0
Record-Route: <sip:216.82.224.202;lr;ftag=gK041bf353>
Via: SIP/2.0/UDP 216.82.224.202;branch=z9hG4bK4fc9.537b5242.0
Via: SIP/2.0/UDP 192.168.16.7:5060;branch=z9hG4bK04B536ff50d9ea38963
From: "WIRELESS CALLER " <sip:+14029812853@192.168.16.7>;tag=gK041bf353
To: <sip:+14026710461@216.82.224.202>
Call-ID: 220498335_117416197@192.168.16.7
CSeq: 577745 INVITE
Max-Forwards: 69
Contact: "WIRELESS CALLER " <sip:+14029812853@192.168.16.7:5060>
Remote-Party-ID: "WIRELESS CALLER " <sip:+14029812853@216.82.224.202:5060>;privacy=off
P-Asserted-Identity: "WIRELESS CALLER " <sip:+14029812853@216.82.224.202:5060>
Content-Length: 328
Content-Disposition: session; handling=required
Content-Type: application/sdp

v=0
o=Sonus_UAC 183083 596337 IN IP4 192.168.16.7
s=SIP Media Capabilities
c=IN IP4 67.231.4.9
t=0 0
m=audio 16078 RTP/AVP 0 18 96 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:96 iLBC/8000
a=fmtp:96 mode=30
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:30

*Aug 4 14:52:21.436: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Aug 4 14:52:21.436: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x68B0BEA8) with key=[192] to table
*Aug 4 14:52:21.440: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:
*Aug 4 14:52:21.440: //-1/000000000000/SIP/Info/ccsip_iwf_init:
*Aug 4 14:52:21.440: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:
*Aug 4 14:52:21.440: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization: Entry...
*Aug 4 14:52:21.440: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 216.82.224.202,Port 5060, Transport 1, SentBy Port 5060
*Aug 4 14:52:21.440: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Aug 4 14:52:21.440: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling ip_best_local_address()
*Aug 4 14:52:21.440: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 10.80.110.3
*Aug 4 14:52:21.440: //-1/5A896C25810B/SIP/State/sipSPIChangeState: 0x68B0BEA8 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Aug 4 14:52:21.440: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 216.82.224.202,Port 5060, Transport 1, SentBy Port 5060
*Aug 4 14:52:21.440: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Aug 4 14:52:21.440: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 216.82.224.202,Port 5060, Transport 1, SentBy Port 5060
*Aug 4 14:52:21.440: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Aug 4 14:52:21.440: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling ip_best_local_address()
*Aug 4 14:52:21.440: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 10.80.110.3
*Aug 4 14:52:21.444: //-1/5A896C25810B/SIP/Info/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
*Aug 4 14:52:21.444: //-1/5A896C25810B/SIP/Info/sipSPIUpdateIdentity: SIP->PSTN: Setting Calling Name to WIRELESS CALLER
*Aug 4 14:52:21.444: //-1/5A896C25810B/SIP/Info/sipSPIUpdateIdentity: SIP->PSTN: Setting Calling Number to +14029812853
*Aug 4 14:52:21.444: //-1/5A896C25810B/SIP/Info/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
*Aug 4 14:52:21.444: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Aug 4 14:52:21.444: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentCSTA: No CSTA found in inbound container
*Aug 4 14:52:21.444: //-1/5A896C25810B/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Aug 4 14:52:21.444: //-1/5A896C25810B/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x68B0BEA8 key=220498335_117416197@192.168.16.7+14026710461
*Aug 4 14:52:21.444: //-1/5A896C25810B/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on carrier id
*Aug 4 14:52:21.444: //-1/5A896C25810B/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: +14026710461
*Aug 4 14:52:21.444: //-1/5A896C25810B/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on destination pattern: +14029812853
*Aug 4 14:52:21.444: //-1/5A896C25810B/SIP/Info/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name WIRELESS CALLER , number +14029812853, Calling oct3 0x00, oct_3a 0x81, Called number +14026710461
*Aug 4 14:52:21.448: //-1/5A896C25810B/SIP/Info/sipSPIGetViaHostInURLFormat: VIA URL:sip:216.82.224.202:5060, Host:216.82.224.202
*Aug 4 14:52:21.448: //-1/5A896C25810B/SIP/Info/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : +14029812853
*Aug 4 14:52:21.448: //-1/5A896C25810B/SIP/Info/sipSPIGetFromCalledPartyId: P-Called-Party-ID header not found
*Aug 4 14:52:21.448: //-1/5A896C25810B/SIP/Info/sipSPIGetPeerByCalledPartyId: P-Called-Party-ID not found or parse error
*Aug 4 14:52:21.448: //-1/5A896C25810B/SIP/Info/sipSPIGetCallConfig: No match found for P-Called-Party-ID
*Aug 4 14:52:21.448: //-1/5A896C25810B/SIP/Info/ccsip_validate_and_update_calling_info: PAI/PPI not configuredi for this dial-peer(1), use RPID/FROM header data
*Aug 4 14:52:21.448: //-1/5A896C25810B/SIP/Info/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with RPID header data
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPISetInfoFromRpid: Received current remote name: WIRELESS CALLER , current remote number: +14029812853
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPISetInfoFromRpid: Received ;screen= ;privacy=off -> Setting Octet3A 0x80, extended_privacy 0x00
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPIGetCallConfig: Peer tag 1 matched for incoming call
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPICheckReliableProvStringtag: Unable to access supported header values
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Aug 4 14:52:21.452: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Aug 4 14:52:21.452: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling ip_best_local_address()
*Aug 4 14:52:21.452: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 10.80.110.3
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPICheckReliableProvStringtag: Unable to access supported header values
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=0
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPIContinueNewMsgInvite: Calling name WIRELESS CALLER , number +14029812853, Calling oct3 0x00, oct_3a 0x80, ext_priv 0x00, Called number +14026710461, oct3 0x00
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE
*Aug 4 14:52:21.452: //-1/5A896C25810B/SIP/Info/sipSPICheckReliableProvStringtag: Unable to access supported header values
*Aug 4 14:52:21.456: //-1/5A896C25810B/SIP/Info/sipSPIValidateRequestUri: Not Enabled
*Aug 4 14:52:21.456: //-1/5A896C25810B/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Info/sipSPI_ipip_IsSDPPassthruEnabled: - 0
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Event/Session-Timer/sipSTSLMain: dir:2, method:102, resp_code:0, container:68D7AA54
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Info/Session-Timer/sipSTSLExtractSessionExpiresHdr: Not received session expires header
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Info/Session-Timer/sipSTSLGetInternalSREvent: E_STSL_INITIAL_SR_REQ
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Event/Session-Timer/sipSTSLPrintTDContainer: Peer-Event: E_STSL_LEG_BY_LEG, SE Value:0, SE Refresher:none, Min-SE Value:1800, flags:2000
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Info/Session-Timer/sipSTSLMain:
SE: 0;refresher:none peer refresher:none, flags:2000, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:1800, Configured Min-SE:1800
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Info/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Info/sipSPIProcessDiversionHeader: No diversion headers recvd from app container
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Info/sipSPIProcessReplacesHeader: No replaces hdr found
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Info/sipSPIValidateConnectionAddress: Dest port = 16078
SIP: (27) Attribute mid, level 1 instance 1 not found.
*Aug 4 14:52:21.456: //27/5A896C25810B/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling ip_best_local_address()
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: return addr 10.80.110.3
*Aug 4 14:52:21.460: //27/5A896C25810B/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.80.110.3
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(96) reserved for codec ilbc
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(96) could not be reserved
as its in use by other codec ilbc
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIUpdateDynamicPT: Requested payload-Type (96) is reserved by another application
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 116
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 96
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAllocateFreeDynamicPT: Allocating free Dynamic Payload : 98 for Codec:
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec No Codec
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 97
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(101) could not be reserved
as its in use by other codec No Codec
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIUpdateDynamicPT: Requested payload-Type (101) is reserved by another application
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 99
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 101
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAllocateFreeDynamicPT: Allocating free Dynamic Payload : 99 for Codec:
*Aug 4 14:52:21.460: //27/5A896C25810B/SIP/Info/sipSPIDoAudioNegotiation: Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1
*Aug 4 14:52:21.460: //27/5A896C25810B/SIP/Info/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled
*Aug 4 14:52:21.460: //27/5A896C25810B/SIP/Info/sipSPISetFaxFlags: FAX_PASSTHROUGH = 0, END_FAX_PASSTHROUGH = 0
*Aug 4 14:52:21.460: //27/5A896C25810B/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 99
*Aug 4 14:52:21.460: //27/5A896C25810B/SIP/Info/sipSPIReserveRtpNtePayload: Reserved the payload type 101 for RTP-NTE
*Aug 4 14:52:21.460: //27/5A896C25810B/SIP/Info/sipSPIDoDTMFRelayNegotiation: RTP-NTE DTMF relay option
*Aug 4 14:52:21.460: //27/5A896C25810B/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of partial named event(NE) match in fmtp list of events.
*Aug 4 14:52:21.460: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: V150 NSE payload = 0, SSE payload = 0, SPRT payload=0
*Aug 4 14:52:21.460: //27/5A896C25810B/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
*Aug 4 14:52:21.460: //27/5A896C25810B/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
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1
payload_type=0, codec_bytes=160, codec=g711ulaw, dtmf_relay=rtp-nte
stream_type=voice+dtmf (1), dest_ip_address=67.231.4.9, dest_port=16078
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING)
*Aug 4 14:52:21.464: //27/5A896C25810B/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

*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.80.110.3
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/ccsip_offer_ans_handle_rcvd_sdp:
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/ccsip_offer_ans_process_event:
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_IDLE, event:E_SIP_INVITE_SDP_RCVD
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/ccsip_offer_ans_is_invite_offer_valid: - 1
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/ccsip_offer_ans_common_offer_rcvd_hdlr:
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/ccsip_iwf_handle_network_event:
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/ccsip_iwf_process_event:
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_RCVD_SDP
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sip_iwf_def_ed_rcvd_sdp_hdlr:
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo:
CallID 27, sdp 0x68EBFC80 channels 0x68B0D4FC
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/copy_channels:
callId 27 size 0 ptr 0x67EF3080)
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo: stream_callid = 27, peer_stream_callid = -1
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo:
Hndl ptype 0 mline 1
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo: Selecting codec g711ulaw
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/codec_found:
Codec to be matched: 5
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/codec_found: codecs[i] = 5 & codec = 5 are same..
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 5

*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sipSPI_ipip_update_codec_params_in_channelInfo:
*Aug 4 14:52:21.464: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sipSPI_ipip_update_codec_params_in_channelInfo:
Adding negotiated codec 5 ptype 0 time 20, bytes 160 as channel 0 mline 1 ss 1 67.231.4.9:16078
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Error/sipSPI_ipip_update_codec_params_in_channelInfo:
failed to update call entry
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo: Media negotiation done: stream->negotiated_ptime=0,stream->negotiated_codec_bytes=160,stream->mline_index=1, media_ndx=1
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo:
Hndl ptype 18 mline 1
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Media/sipSPISelectCodecVersion: Codec (g729r8) is not in preferred list
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sipSPI_ipip_update_static_codec_params: An exact codec match not configured, using interoperable codec g729br8
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo: Selecting codec g729br8
*Aug 4 14:52:21.464: //27/5A896C25810B/SIP/Info/codec_found:
Codec to be matched: 12
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/codec_found: codecs[i] = 12 & codec = 12 are same..
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 12

*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPI_ipip_update_codec_params_in_channelInfo:
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPI_ipip_GetCodecBytePtimeFromSdp:
SIP: (27) Attribute ptime, level 1 instance 1 not found.
*Aug 4 14:52:21.468: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g729br8 ptime :0, codecbytes: 0
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPI_ipip_GetCodecBytePtimeFromSdp: Codec bytes 0, use default packet rate 20
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPI_ipip_update_codec_params_in_channelInfo:
Adding negotiated codec 12 ptype 18 time 0, bytes 20 as channel 1 mline 1 ss 1 67.231.4.9:16078
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Error/sipSPI_ipip_update_codec_params_in_channelInfo:
failed to update call entry
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo:
Hndl ptype 96 mline 1
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPI_ipip_update_dynamic_codec_params:
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPI_ipip_upd_ilbc_params:
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/codec_found:
Codec to be matched: 33
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/codec_found: No match for the codecs found..
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo:
Hndl ptype 101 mline 1
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPI_ipip_update_dynamic_codec_params:
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPI_ipip_upd_2833_dtmf_params:
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPI_ipip_upd_2833_dtmf_params: setting ipip_caps DTMF to RFC2833: callid = 27, dtmf = 6
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Error/sipSPI_ipip_update_call_entry:
failed to update call entry
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[0].codec = 5

*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[1].codec = 12

*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[2].codec = -1

*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sip_iwf_sip_copy_sdp_to_channelInfo: srcChannelID = -1, dstChannelID = 27
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Media/sipSPIDisplayStreamInfo:
Stream type : voice+dtmf
Media line : 1
State : STREAM_ADDING (2)
Stream address type : 1
Callid : 27
Peer Callid : -1
RTP/SRTP Negotiated : 8
Negotiated Codec : g711ulaw, bytes :160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated DTMF relay : rtp-nte
Negotiated NTE payload : 101 (tx), 101 (rx)
Negotiated CN payload : 0
Media Srce Addr/Port : [10.80.110.3]:0
Media Dest Addr/Port : [67.231.4.9]:16078

*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPI_ipip_calculate_channel_count: Number of streams to report = 1
*Aug 4 14:52:21.468: //-1/xxxxxxxxxxxx/SIP/Error/sipSPI_ipip_set_channel_count: Unable to set CHANNEL_COUNT for callid 27
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Error/sip_iwf_sip_copy_sdp_to_channelInfo: Channel count is not set at this point. Not SIP-SIP or SET_MODE is not done.
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_RCVD
*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Media/sipSPIUpdCallWithSdpInfo:
Stream type : voice+dtmf
Media line : 1
State : STREAM_ADDING (2)
Stream address type : 1
Callid : 27
Negotiated Codec : g711ulaw, bytes :160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated DTMF relay : rtp-nte
Negotiated NTE payload : 101 (tx), 101 (rx)
Negotiated CN payload : 0
Media Srce Addr/Port : [10.80.110.3]:0
Media Dest Addr/Port : [67.231.4.9]:16078

*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPIHandleInviteMedia:
Negotiated Codec : g711ulaw, 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 V150.1 Modem Passthrough: 0 0
Preferred and Negotiated V150.1 Modem Relay: 0 0
Preferred and Negotiated Modem Relay GwXid: 1 0

*Aug 4 14:52:21.468: //27/5A896C25810B/SIP/Info/sipSPIDoQoSNegotiationWithMediaLine: QOS negotiation for mline_index 1
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPIDoStreamQoSNegotiation: Best effort
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Aug 4 14:52:21.472: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 17182 for stream 1
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Reserving rtp port for stream 1, src_port=17182
*Aug 4 14:52:21.472: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Setting stream 1 portnum to 17182
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPIUpdateSrcSdpVariablePart:
SIP update src sdp, negoitated codec 5, payload type 0

*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Negotiated method of dtmf relayand pyld: 6 101

*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPIUpdateSrcSdpVariablePart: max_event 16
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPIAddBillingInfoToCcb: sipCallId for billing records = 220498335_117416197@192.168.16.7
*Aug 4 14:52:21.472: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentCPA: No CPA found in inbound container
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPIProcessCPA: No x-cisco-cpa content found
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPI_ipip_ExtractPassthruCopyListHdrsFromSipContainer: Unsupported header passthru is not configured and no headers are present in hdr_hash_queue
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPI_ipip_IsContentPassthruEnabled: - 0
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPI_ipip_ExtractPassthruContentFromSipContainer: Passthru Content Not Enabled
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/ccsip_api_call_setup_ind: Unable to add unsupp headers to container
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/ccsip_api_call_setup_ind: Set Protocol information
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 24
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPI_ipip_store_channel_info: Store channelInfo in CallInfo
*Aug 4 14:52:21.472: //27/5A896C25810B/SIP/Info/sipSPI_ipip_store_channel_info: dtmf negotiation done, storing negotiated dtmf = 6,
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Media/sipSPIDisplayStreamInfo:
Stream type : voice+dtmf
Media line : 1
State : STREAM_ADDING (2)
Stream address type : 1
Callid : 27
Peer Callid : -1
RTP/SRTP Negotiated : 8
Negotiated Codec : g711ulaw, bytes :160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated DTMF relay : rtp-nte
Negotiated NTE payload : 101 (tx), 101 (rx)
Negotiated CN payload : 0
Media Srce Addr/Port : [10.80.110.3]:17182
Media Dest Addr/Port : [67.231.4.9]:16078

*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Info/sipSPI_ipip_calculate_channel_count: Number of streams to report = 1
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Info/sipSPI_ipip_store_channel_info: Setting CHANNEL_COUNT = 1 for callid 27
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Info/sipSPIShrlCall: Check peer: 1 for Shared-Line call, callid: 27
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Info/ccsip_set_bearer_capability:
Bearer Capability: Speech (0x00)
*Aug 4 14:52:21.476: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container
*Aug 4 14:52:21.476: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container
*Aug 4 14:52:21.476: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateNewRawMsg: No Data to form The Raw Message

*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Info/sipSPIContinueNewMsgInvite: ccsip_api_call_setup_ind returned: SIP_SUCCESS
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 1B to table
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Info/sipSPIUpdateCallEntry:
Call 27 set InfoType to SPEECH
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:100, container:68D6FFD4
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Info/Session-Timer/sipSTSLValidateSessRefreshMsg: Ignoring 1xx response for session timer processing
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Info/sipSPISendInviteResponse: Associated container=0x68D6FFD4 to Invite Response 100
*Aug 4 14:52:21.476: //27/5A896C25810B/SIP/Transport/sipSPITransportSendMessage: msg=0x68EB4654, addr=216.82.224.202, port=5060, sentBy_port=5060, local_addr=, is_req=0, transport=1, switch=0, callBack=0x0P4 10.80.110.3
t=0 0
m=audio 16494 RTP/AVP 0 18 101
c=IN IP4 10.80.110.3
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=yes
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

*Aug 4 14:52:21.604: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [216.82.224.202]:5060, local_address:[ - ]
*Aug 4 14:52:21.604: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Aug 4 14:52:21.604: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
*Aug 4 14:52:21.604: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Aug 4 14:52:21.604: //28/5A896C25810B/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 404 Number not in e164 format, example +12125551212
Via: SIP/2.0/UDP 10.80.110.3:5060;branch=z9hG4bK91870;received=68.96.19.74
From: "WIRELESS CALLER " <sip:+14029812853@10.80.110.3>;tag=33F9F1C-490
To: <sip:14026710461@216.82.224.202>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.a3df
Call-ID: 5A93CC96-785B11E7-8111C70A-1EA9078C@10.80.110.3
CSeq: 101 INVITE
Server: Bandwidth.com TRM (bw7.gold.13)
Content-Length: 0

*Aug 4 14:52:21.604: //28/5A896C25810B/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Info/sipSPIPresendProcessing: Presend Processing called for 1 event
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Info/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Info/ccsip_offer_ans_handle_call_active:
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Info/ccsip_offer_ans_process_event:
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_SENT, event:E_SIP_DIALOG_ESTD
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Info/ccsip_offer_ans_process_event: O/A - cnfsm ret 2
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Info/sipSPISendAck: Associated container=0x68D7A584 to Ack
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Transport/sipSPITransportSendMessage: msg=0x68EAC634, addr=216.82.224.202, port=5060, sentBy_port=0, local_addr=, is_req=1, transport=1, switch=0, callBack=0x0
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Aug 4 14:52:21.608: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:216.82.224.202, rport:5060 with laddr:

*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x68EAC634
*Aug 4 14:52:21.608: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x68EAC634, addr=216.82.224.202, port=5060, local_addr=, connId=2 for UDP
*Aug 4 14:52:21.608: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[28], src[4]
*Aug 4 14:52:21.608: //28/5A896C25810B/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(1) for outgoing call
*Aug 4 14:52:21.608: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container
*Aug 4 14:52:21.608: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/sipSPI_ipip_ExtractPassthruCopyListHdrsFromSipContainer: Unsupported header passthru is not configured and no headers are present in hdr_hash_queue
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/sipSPI_ipip_IsContentPassthruEnabled: - 0
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/sipSPI_ipip_ExtractPassthruContentFromSipContainer: Passthru Content Not Enabled
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/sipAddSipContainerToCallEntry: Unable to add passthru hdrs to container
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/State/sipSPIChangeState: 0x68B11898 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/ccsip_call_statistics: Requesting stats for callid=28
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/ccsip_call_statistics: Stats request failed for callid=28, dstCallID=-1, rc=-7
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
*Aug 4 14:52:21.612: //28/5A896C25810B/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured
*Aug 4 14:52:21.612: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Aug 4 14:52:21.612: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 8
*Aug 4 14:52:21.616: //28/5A896C25810B/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT
*Aug 4 14:52:21.616: //28/5A896C25810B/SIP/Info/Session-Timer/sipSTSLMain:
SE: 0;refresher:none peer refresher:none, flags:2000, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:1800, Configured Min-SE:1800
*Aug 4 14:52:21.616: //28/5A896C25810B/SIP/Info/sipSPIIcpifUpdate: CallState: 2 Playout: 0 DiscTime:5450125 ConnTime 0
*Aug 4 14:52:21.616: //28/5A896C25810B/SIP/Media/sipSPIDestroyRtpSession: stream:69006698
*Aug 4 14:52:21.616: //28/5A896C25810B/SIP/State/sipSPIChangeState: 0x68B11898 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*Aug 4 14:52:21.616: //28/5A896C25810B/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x68B11898
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : +14029812853
Called Number : 14026710461
Source IP Address (Sig ): 10.80.110.3
Destn SIP Req Addr:Port : 216.82.224.202:5060
Destn SIP Resp Addr:Port : 216.82.224.202:5060
Destination Name : 216.82.224.202

*Aug 4 14:52:21.616: //28/5A896C25810B/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1

[Abhay Singh Reyal]

As per the debugs Invite received is as below 

Received:
INVITE sip:+14026710461@68.96.19.74:5060;transport=udp SIP/2.0
Record-Route: <sip:216.82.224.202;lr;ftag=gK041bf353>
Via: SIP/2.0/UDP 216.82.224.202;branch=z9hG4bK4fc9.537b5242.0
Via: SIP/2.0/UDP 192.168.16.7:5060;branch=z9hG4bK04B536ff50d9ea38963
From: "WIRELESS CALLER " <sip:+14029812853@192.168.16.7>;tag=gK041bf353
To: <sip:+14026710461@216.82.224.202>
Call-ID: 220498335_117416197@192.168.16.7

However I do not see any further SIP messages for this call leg. Another call leg which will be towards CUCM, for that no invite has been sent however only 404 received for number not being in e164 format

Received:
SIP/2.0 404 Number not in e164 format, example +12125551212
Via: SIP/2.0/UDP 10.80.110.3:5060;branch=z9hG4bK91870;received=68.96.19.74
From: "WIRELESS CALLER " <sip:+14029812853@10.80.110.3>;tag=33F9F1C-490
To: <sip:14026710461@216.82.224.202>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.a3df
Call-ID: 5A93CC96-785B11E7-8111C70A-1EA9078C@10.80.110.3
CSeq: 101 INVITE
Server: Bandwidth.com TRM (bw7.gold.13)
Content-Length: 0

Kindly make another test call ,collect below debugs and share show run

debug ccsip messages

debug voip ccapi inout

Regards

Abhay

[Jonathan Forbes]

here is the logs

debug ccsip messages

SIP Call messages tracing is enabled
Router#term mon
Router#
*Aug 4 15:49:17.692: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:+14026710461@68.96.19.74:5060;transport=udp SIP/2.0
Record-Route: <sip:216.82.224.202;lr;ftag=gK080e9be4>
Via: SIP/2.0/UDP 216.82.224.202;branch=z9hG4bK4db1.f3272af2.0
Via: SIP/2.0/UDP 192.168.16.7:5060;branch=z9hG4bK08B3ee74ea2e600815b
From: "WIRELESS CALLER " <sip:+14029812853@192.168.16.7>;tag=gK080e9be4
To: <sip:+14026710461@216.82.224.202>
Call-ID: 222856149_74946594@192.168.16.7
CSeq: 575670 INVITE
Max-Forwards: 69
Contact: "WIRELESS CALLER " <sip:+14029812853@192.168.16.7:5060>
Remote-Party-ID: "WIRELESS CALLER " <sip:+14029812853@216.82.224.202:5060>;privacy=off
P-Asserted-Identity: "WIRELESS CALLER " <sip:+14029812853@216.82.224.202:5060>
Content-Length: 328
Content-Disposition: session; handling=required
Content-Type: application/sdp

v=0
o=Sonus_UAC 737129 640323 IN IP4 192.168.16.7
s=SIP Media Capabilities
c=IN IP4 67.231.4.8
t=0 0
m=audio 15676 RTP/AVP 0 18 96 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:96 iLBC/8000
a=fmtp:96 mode=30
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:30

*Aug 4 15:49:17.728: //33/4EC8AE8F8120/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 216.82.224.202;branch=z9hG4bK4db1.f3272af2.0,SIP/2.0/UDP 192.168.16.7:5060;branch=z9hG4bK08B3ee74ea2e600815b
From: "WIRELESS CALLER " <sip:+14029812853@192.168.16.7>;tag=gK080e9be4
To: <sip:+14026710461@216.82.224.202>
Date: Fri, 04 Aug 2017 15:49:17 GMT
Call-ID: 222856149_74946594@192.168.16.7
CSeq: 575670 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0

*Aug 4 15:49:17.728: //34/4EC8AE8F8120/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:14026710461@216.82.224.202:5060 SIP/2.0
Via: SIP/2.0/UDP 10.80.110.3:5060;branch=z9hG4bKC941
Remote-Party-ID: "WIRELESS CALLER " <sip:+14029812853@10.80.110.3>;party=calling;screen=no;privacy=off
From: "WIRELESS CALLER " <sip:+14029812853@10.80.110.3>;tag=373BFB0-4F6
To: <sip:14026710461@216.82.224.202>
Date: Fri, 04 Aug 2017 15:49:17 GMT
Call-ID: 4ECCF43F-786311E7-8126C70A-1EA9078C@10.80.110.3
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 1321774735-2019758567-2166408970-0514393996
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1501861757
Contact: <sip:+14029812853@10.80.110.3:5060>
Expires: 180
Allow-Events: telephone-event
Max-Forwards: 68
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 280

v=0
o=CiscoSystemsSIP-GW-UserAgent 3340 4270 IN IP4 10.80.110.3
s=SIP Call
c=IN IP4 10.80.110.3
t=0 0
m=audio 18876 RTP/AVP 0 18 101
c=IN IP4 10.80.110.3
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=yes
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

*Aug 4 15:49:17.824: //34/4EC8AE8F8120/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 404 Number not in e164 format, example +12125551212
Via: SIP/2.0/UDP 10.80.110.3:5060;branch=z9hG4bKC941;received=68.96.19.74
From: "WIRELESS CALLER " <sip:+14029812853@10.80.110.3>;tag=373BFB0-4F6
To: <sip:14026710461@216.82.224.202>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.256a
Call-ID: 4ECCF43F-786311E7-8126C70A-1EA9078C@10.80.110.3
CSeq: 101 INVITE
Server: Bandwidth.com TRM (bw7.gold.13)
Content-Length: 0

*Aug 4 15:49:17.832: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:14026710461@216.82.224.202:5060 SIP/2.0
Via: SIP/2.0/UDP 10.80.110.3:5060;branch=z9hG4bKC941
From: "WIRELESS CALLER " <sip:+14029812853@10.80.110.3>;tag=373BFB0-4F6
To: <sip:14026710461@216.82.224.202>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.256a
Date: Fri, 04 Aug 2017 15:49:17 GMT
Call-ID: 4ECCF43F-786311E7-8126C70A-1EA9078C@10.80.110.3
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

*Aug 4 15:49:17.832: //33/4EC8AE8F8120/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 216.82.224.202;branch=z9hG4bK4db1.f3272af2.0,SIP/2.0/UDP 192.168.16.7:5060;branch=z9hG4bK08B3ee74ea2e600815b
From: "WIRELESS CALLER " <sip:+14029812853@192.168.16.7>;tag=gK080e9be4
To: <sip:+14026710461@216.82.224.202>;tag=373C018-221
Date: Fri, 04 Aug 2017 15:49:17 GMT
Call-ID: 222856149_74946594@192.168.16.7
CSeq: 575670 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Reason: Q.850;cause=1
Content-Length: 0

*Aug 4 15:49:17.920: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:+14026710461@68.96.19.74:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 216.82.224.202;branch=z9hG4bK4db1.f3272af2.0
From: "WIRELESS CALLER " <sip:+14029812853@192.168.16.7>;tag=gK080e9be4
Call-ID: 222856149_74946594@192.168.16.7
To: <sip:+14026710461@216.82.224.202>;tag=373C018-221
CSeq: 575670 ACK
Max-Forwards: 70
User-Agent: Bandwidth.com TRM (bw7.gold.13)
Content-Length: 0

debug voip ccapi inout

*Aug 4 15:51:46.723: //-1/A79AB7AF8127/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=+14029812853
----- ccCallInfo IE subfields -----
cisco-ani=+14029812853
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=+14026710461
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

*Aug 4 15:51:46.723: //-1/A79AB7AF8127/CCAPI/cc_api_call_setup_ind_common:
Interface=0x67ABA72C, Call Info(
Calling Number=+14029812853,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=+14026710461(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=1, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=35
*Aug 4 15:51:46.723: //-1/A79AB7AF8127/CCAPI/ccCheckClipClir:
In: Calling Number=+14029812853(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Aug 4 15:51:46.723: //-1/A79AB7AF8127/CCAPI/ccCheckClipClir:
Out: Calling Number=+14029812853(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Aug 4 15:51:46.723: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Aug 4 15:51:46.723: :cc_get_feature_vsa malloc success
*Aug 4 15:51:46.723: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Aug 4 15:51:46.723: cc_get_feature_vsa count is 1
*Aug 4 15:51:46.723: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Aug 4 15:51:46.723: :FEATURE_VSA attributes are: feature_name:0,feature_time:1761190720,feature_id:35
*Aug 4 15:51:46.723: //35/A79AB7AF8127/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=+14029812853(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=+14026710461(TON=Unknown, NPI=Unknown))
*Aug 4 15:51:46.727: //35/A79AB7AF8127/CCAPI/cc_process_call_setup_ind:
Event=0x6866A4A0
*Aug 4 15:51:46.727: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number +14026710461
*Aug 4 15:51:46.727: //35/A79AB7AF8127/CCAPI/ccCallSetContext:
Context=0x68FB2DB0
*Aug 4 15:51:46.727: //35/A79AB7AF8127/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 35 with tag 1 to app "_ManagedAppProcess_Default"
*Aug 4 15:51:46.731: //35/A79AB7AF8127/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
*Aug 4 15:51:46.731: //35/A79AB7AF8127/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=1, Params=0x68FB1940, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
*Aug 4 15:51:46.735: //35/A79AB7AF8127/CCAPI/ccCheckClipClir:
In: Calling Number=+14029812853(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Aug 4 15:51:46.735: //35/A79AB7AF8127/CCAPI/ccCheckClipClir:
Out: Calling Number=+14029812853(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Aug 4 15:51:46.735: //35/A79AB7AF8127/CCAPI/ccCallSetupRequest:
Destination Pattern=.........., Called Number=14026710461, Digit Strip=FALSE
*Aug 4 15:51:46.735: //35/A79AB7AF8127/CCAPI/ccCallSetupRequest:
Calling Number=+14029812853(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=14026710461(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=WIRELESS CALLER
Account Number=+14029812853, Final Destination Flag=TRUE,
Guid=A79AB7AF-7863-11E7-8127-C70A1EA9078C, Outgoing Dial-peer=1
*Aug 4 15:51:46.735: //35/A79AB7AF8127/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=+14029812853
----- ccCallInfo IE subfields -----
cisco-ani=+14029812853
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=14026710461
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

*Aug 4 15:51:46.735: //35/A79AB7AF8127/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x67ABA72C, Interface Type=3, Destination=, Mode=0x0,
Call Params(Calling Number=+14029812853,(Calling Name=WIRELESS CALLER )(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=14026710461(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=1, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Aug 4 15:51:46.735: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Aug 4 15:51:46.735: :cc_get_feature_vsa malloc success
*Aug 4 15:51:46.735: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Aug 4 15:51:46.735: cc_get_feature_vsa count is 2
*Aug 4 15:51:46.735: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Aug 4 15:51:46.735: :FEATURE_VSA attributes are: feature_name:0,feature_time:1761190496,feature_id:36
*Aug 4 15:51:46.739: //36/A79AB7AF8127/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
*Aug 4 15:51:46.739: //36/A79AB7AF8127/CCAPI/ccCallSetContext:
Context=0x68FB18F0
*Aug 4 15:51:46.739: //35/A79AB7AF8127/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=1
*Aug 4 15:51:46.743: //36/A79AB7AF8127/CCAPI/cc_api_call_proceeding:
Interface=0x67ABA72C, Progress Indication=NULL(0)
*Aug 4 15:51:46.835: //36/A79AB7AF8127/CCAPI/cc_api_call_disconnected:
Cause Value=1, Interface=0x67ABA72C, Call Id=36
*Aug 4 15:51:46.835: //36/A79AB7AF8127/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=1, Retry Count=0)
*Aug 4 15:51:46.835: //35/A79AB7AF8127/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
*Aug 4 15:51:46.839: //36/A79AB7AF8127/CCAPI/ccCallSetAAA_Accounting:
Accounting=1, Call Id=36
*Aug 4 15:51:46.839: //36/A79AB7AF8127/CCAPI/ccCallDisconnect:
Cause Value=1, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=1)
*Aug 4 15:51:46.839: //36/A79AB7AF8127/CCAPI/ccCallDisconnect:
Cause Value=1, Call Entry(Responsed=TRUE, Cause Value=1)
*Aug 4 15:51:46.839: //36/A79AB7AF8127/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x67ABA72C, Tag=0x0, Call Id=36,
Call Entry(Disconnect Cause=1, Voice Class Cause Code=0, Retry Count=0)
*Aug 4 15:51:46.839: //36/A79AB7AF8127/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Aug 4 15:51:46.839: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Aug 4 15:51:46.839: :cc_free_feature_vsa freeing 68F9A258
*Aug 4 15:51:46.839: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Aug 4 15:51:46.839: vsacount in free is 1
*Aug 4 15:51:46.843: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 14026710461
*Aug 4 15:51:46.843: //35/A79AB7AF8127/CCAPI/ccCallDisconnect:
Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Aug 4 15:51:46.843: //35/A79AB7AF8127/CCAPI/ccCallDisconnect:
Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17)
*Aug 4 15:51:46.931: //35/A79AB7AF8127/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x67ABA72C, Tag=0x0, Call Id=35,
Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0)
*Aug 4 15:51:46.931: //35/A79AB7AF8127/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Aug 4 15:51:46.931: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Aug 4 15:51:46.931: :cc_free_feature_vsa freeing 68F9A338
*Aug 4 15:51:46.931: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Aug 4 15:51:46.931: vsacount in free is 0

[Jonathan Forbes]

I am seeing this error on my debug

SIP/2.0 400 Bad Request - 'Invalid Host'

[Abhay Singh Reyal · Accepted solution]

---- Invite received is for e164 format from provider

From: "WIRELESS CALLER " <sip:+14029812853@192.168.16.7>;tag=gK080e9be4

To: <sip:+14026710461@216.82.224.202>

---- Invite sent to CUCM is non e164 format 

From: "WIRELESS CALLER " <sip:+14029812853@10.80.110.3>;tag=373BFB0-4F6
To: <sip:14026710461@216.82.224.202>

That is why you received below error from CUCM

Received:
SIP/2.0 404 Number not in e164 format, example +12125551212
Via: SIP/2.0/UDP 10.80.110.3:5060;branch=z9hG4bKC941;received=68.96.19.74
From: "WIRELESS CALLER " <sip:+14029812853@10.80.110.3>;tag=373BFB0-4F6
To: <sip:14026710461@216.82.224.202>;tag=f5da119de3db22dcaa2abb8ea9fec0ce.256a

The number formatting needs to be changed and see if this helps.

Regards

Abhay