CUBE never return back SIP messages to sender

Hesham Mohamed El Sayed · ‎09-21-2022

HI everyone

this is so exhausting , my CUBE is behind a NAT and i want to copy the FROM ( public IP of caller ) and use it in via header to send SIP traffic back , i am unable to modify the RECIEVED INVITE at all ( as below ), only the SEND replies from CUBE gets updated ( trying) , yet after changing the via header in sent replies , the reply never reached the public ip , and is there a way to manipulate the received invite

can you please guys help me CUBE is not returning the sip messages to the correct sender

voice service voip
ip address trusted list
ipv4 0.0.0.0 0.0.0.0
mode border-element
allow-connections sip to sip
sip
registrar server expires max 3600 min 305
sip-profiles inbound
sip-profiles 30 inbound

voice class sip-profiles 30
request INVITE sip-header From copy "<sip:(.*)>.*" u01
request INVITE sip-header via modify "(SIP/2.0/UDP )(.*)(:.*)" "\1\u01\3"

Received:
INVITE sip:141@one.asuscomm.com SIP/2.0
Via: SIP/2.0/UDP 172.26.8.2:52544;rport;branch=z9hG4bKPj8558fe4743614d9b992ed7c691dd4231
Max-Forwards: 70
From: <sip:15.236.40.101>;tag=f3259e038cc3459a9e1e4b2dcda67604
To: <sip:141@one.asuscomm.com>
Contact: <sip:172.26.8.2:52544;ob>
Call-ID: 9590670f708d4e459b5749be40b9bfe3
CSeq: 29897 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: MicroSIP/3.21.2
Content-Type: application/sdp
Content-Length: 333

Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 15.236.40.101:52544;rport;branch=z9hG4bKPjbef7d1457550477e8f77173d77d4586a
From: <sip:15.236.40.101>;tag=acf8bd3132a84c8b9ff39f0efb44b2d6
To: <sip:141@one.asuscomm.com>
Date: Wed, 21 Sep 2022 11:53:44 GMT
Call-ID: 8b1d394ba4d64839a4c522fd1d8e0ec6
CSeq: 31506 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.7.3.M8
Session-ID: 00000000000000000000000000000000;remote=dc826f21fb1c5f179d4a4966674ae95e
Content-Length: 0

b.winter · ‎09-21-2022

Hi,
could you maybe explain in more detail, what are you trying to do?

Honestly, I have no clue, what you are trying to say with your problem description. That's a mile long sentence, probably mixing up multiple things.

But normally, when you are working behind NAT, then you have to modify all the outgoing messages, so that all the relevant SIP headers doesn't contain the CUBE's private IP, but do contain the public IP (which is used for the NAT).
There were already a lot of old posts asking the same. You probably find the necessary sip-profiles there.

What your sip-profile is currently doing for incoming INVITEs is the following:
Copies the IP from the FROM header in to the VIA header, nothing more, nothing less.

Via: SIP/2.0/UDP 172.26.8.2:52544;rport;branch=z9hG4bKPj8558fe4743614d9b992ed7c691dd4231
From: <sip:15.236.40.101>;tag=f3259e038cc3459a9e1e4b2dcda67604

Via: SIP/2.0/UDP 15.236.40.101:52544;rport;branch=z9hG4bKPj8558fe4743614d9b992ed7c691dd4231
From: <sip:15.236.40.101>;tag=f3259e038cc3459a9e1e4b2dcda67604

Note:
You can test your sip-profiles with this tool: https://cway.cisco.com/tools/SipProfileTest/

Hesham Mohamed El Sayed · ‎09-21-2022

in the invite i receive from microsip which is also behind nat , i only received its private IP , how will CUBE know were to return the "trying" message for example , and here is why i changed the via to include the public ip of the microsip

Yet no any reply from cube ever reached on wireshark on that public ip

b.winter · ‎09-21-2022

But in your debug you are sending a 100 Trying... So where does this message go to? Have you taken a packet capture and checked, where this TRYING is sent to?
Not even talking about, that the messages you post doesn't belong to each other - the Call-ID's are different.

Can't you post a complete debug of a call? I mean, without any logs or config, nobody will be able to help...

Hesham Mohamed El Sayed · ‎09-21-2022

here are the logs , my issue is that nothing reaches the UAC public ip , i even send the invite as below with the UAC public ip but on logs i can see it sent TRYING , but nothing was recieved

even when 141 extension existing and was ringing , the ringing cube was sending never reached UAC

*Sep 21 13:30:13.210: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22A63F40 with refCount = 1
*Sep 21 13:30:13.210: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22A65154 with refCount = 1
*Sep 21 13:30:13.210: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [15.236.40.103]:60095, local_address:[192.168.0.250]
*Sep 21 13:30:13.210: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22A63F40
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:141@onesixty.asuscomm.com SIP/2.0
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
Max-Forwards: 70
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>
Contact: <sip:141@15.236.40.103:60095;ob>
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: MicroSIP/3.21.2
Content-Type: application/sdp
Content-Length: 343

v=0
o=- 3872756853 3872756853 IN IP4 15.236.40.103
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4004 RTP/AVP 8 0 101
c=IN IP4 15.236.40.103
b=TIAS:64000
a=rtcp:4005 IN IP4 15.236.40.103
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:1761956497 cname:206e52b339743cb7

*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIIpTrustSilentDiscard: couldn't find the line details from calling_number
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sipSPIIpTrustSilentDiscard: host ip in CONTACT is [15.236.40.103]
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x3F967460) with key=[300] to table
*Sep 21 13:30:13.214: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
*Sep 21 13:30:13.214: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
*Sep 21 13:30:13.214: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
*Sep 21 13:30:13.214: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
*Sep 21 13:30:13.214: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
*Sep 21 13:30:13.214: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: voice conn_reuse configured
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 192.168.0.250 for SIP
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 192.168.0.250 for SIP
*Sep 21 13:30:13.214: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.0.250
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 1
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 192.168.0.250
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x22A65154) counter, current msg->refCount = 2
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/verbose/4096/sipSPIAssignCcbLastRequest: Current ccb(0x3F967460)->last_request = 0x22A65154, refCount = 2
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/info/8192/sipSPIProcessSessionID: SessionID header not present - create uuid
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: Generate uuid
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: Initial Invite SIP-TDM /MF case
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: generated uuid - e671bc7fd0d055dc8ea4681e86de1da5
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/notify/8192/sipSPI_Add_SessionIDtoTDContainer: localUUID - e671bc7fd0d055dc8ea4681e86de1da5remoteUUID - 00000000000000000000000000000000
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/info/2048/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/info/4096/sipSPISetCallingInfo: calling host comes from From: 192.168.0.250
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/verbose/4096/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with FROM header data
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/info/2048/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentGTD: No GTD found in inbound container
*Sep 21 13:30:13.214: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentCSTA: No CSTA found in inbound container
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x3F967460 key=82654f2428964fadb49a3779b0a6e4ee141 balance 1
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/info/8192/sipSPIMatchSrcIpGroup: Match not found on carrier id
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/critical/8192/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 141
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/critical/8192/sipSPIMatchSrcIpGroup: Match not found on destination pattern: 141
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/info/12288/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name , number 141, Calling oct3 0x00, oct_3a 0x80, Called number 141
*Sep 21 13:30:13.214: //-1/5B3558E78288/SIP/Info/verbose/1024/sipSPIGetViaHostInURLFormat: VIA URL:sip:15.236.40.103:60095, Host:15.236.40.103
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/verbose/67584/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : 141
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/critical/1024/sipSPIGetFromCalledPartyId: P-Called-Party-ID header not found
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/critical/1024/sipSPIGetPeerByCalledPartyId: P-Called-Party-ID not found or parse error
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/critical/10240/sipSPIGetCallConfig: No match found for P-Called-Party-ID
*Sep 21 13:30:13.218: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/notify/131072/sipSPIGetCallConfig: peer_tag = 1410, tenant_tag = 0, VRFId = 0
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/2048/sipSPIGetCallConfig: Peer tag 1410 matched for incoming call
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/verbose/1024/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Sep 21 13:30:13.218: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: voice conn_reuse configured
*Sep 21 13:30:13.218: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 192.168.0.250 for SIP
*Sep 21 13:30:13.218: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 192.168.0.250 for SIP
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.0.250
*Sep 21 13:30:13.218: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 1
*Sep 21 13:30:13.218: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 192.168.0.250
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/2048/sipSPISetAudioForcedConfig: audio forced config is set to FALSE
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/verbose/1024/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/verbose/2048/sipSPIGetCallConfig: Media Antitrombone disabled
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/8192/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/2304/sipSPISetMediaFlowMode: xcoder high-density disabled
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/8192/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/1/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/verbose/12288/sipSPIGetModemInfoPerCall: peer_callID=0
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/critical/32768/ccsip_ipip_media_forking_update_preferred_codec: MF: Not a Forked SIP leg..
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/8704/sipSPIGetCallConfig: Incoming: No defer BYE for last
call stats
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/verbose/1/ccsip_set_srtp_config: No Srtp configure for this leg.
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/8192/sipSPIGetCallConfig: Media forking disabled
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/34816/ccsip_ipip_media_forking_anchor_leg_config: MF: en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/34816/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer has no media class recorder.
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/36864/sipSPIMFChangeState: MF: Prev state = 0 & New state = -1
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/32768/ccsip_ipip_media_forking_anchor_leg_reset: MF: Anchor leg config reset done...
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/32768/ccsip_ipip_media_forking_intra_frame_request_config: MF: FIR en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/32768/ccsip_ipip_media_forking_get_forked_leg_config: MF: This leg is not forked call leg.
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/critical/11264/ccsipInitDSCPPolicyInfo: No DSCP Profile configured, No RPH 2 DSCP Mapping and DSCP policing
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/verbose/8192/sipSPIGetCallConfig: Initilise the DSCP policy
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/verbose/8192/sipSPICheckFAAnatAssymetricOrDO2EO: Not a SIP-SIP call or not in FA mode
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/notify/2049/populate_vcc_data: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/notify/8192/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/verbose/2048/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/notify/10240/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/4096/sipSPIContinueNewMsgInvite: Calling name , number 141, Calling oct3 0x00, oct_3a 0x80, ext_priv 0x00, Called number 141, oct3 0x00
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/4096/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/4096/sipSPIContinueNewMsgInvite: Requires reliable-provisional support
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/critical/10240/sipSPIValidateRequestUri: Not Enabled
*Sep 21 13:30:13.218: //-1/5B3558E78288/SIP/Info/info/131072/sipSPIRscmsmAvail: Value returned by check is = 0
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/critical/4096/sipSPICheckOutBoundDpProvisioning: Cannot obtain the the header bitmap
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/critical/8192/sipSPI_ipip_IsSDPPassthruEnabled: - 0
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/info/8192/sipSPIProcessSessionID: SessionID header not present - create uuid
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: Generate uuid
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: Initial Invite SIP-TDM /MF case
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: generated uuid - 5e18cc70f8b35932929dc0ca309e27df
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/notify/8192/sipSPI_Add_SessionIDtoTDContainer: localUUID - 5e18cc70f8b35932929dc0ca309e27dfremoteUUID - 00000000000000000000000000000000
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/info/8192/Session-Timer/sipSTSLExtractSessionExpiresHdr:
Session-Expires value: 1800 refresher: none
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/info/8192/Session-Timer/sipSTSLExtractMinSEHdr: Min-SE Duration: 90
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/info/8192/Session-Timer/sipSTSLGetInternalSREvent: E_STSL_INITIAL_SR_REQ
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/verbose/8192/Session-Timer/sipSTSLInitialSRReqPeerEventGen: sending received session expires to the peer leg
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/info/8192/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
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/critical/1024/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/critical/1024/sipSPIProcessDiversionHeader: No diversion headers recvd from app container
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/critical/16384/sipSPIProcessReplacesHeader: No replaces hdr found
*Sep 21 13:30:13.218: //-1/xxxxxxxxxxxx/SIP/Info/verbose/1024/sipSPIGetContentCPA: No CPA found in inbound container
*Sep 21 13:30:13.218: //299/5B3558E78288/SIP/Info/verbose/1024/sipSPIProcessCPA: No x-cisco-cpa content found
SIP: Warning: Unrecognized attribute (X-nat)
SIP: Warning: Unrecognized attribute (ssrc)
SIP: Trying to parse unsupported attribute at media level
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1/sipSPIDoMediaNegotiation: Number of m-lines = 1
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1/sipSPIValidateConnectionAddress: Dest port = 4004
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: peer_tag=1410
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_media_ip_address_to_bind: VRF id = 0
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 192.168.0.250 for SIP
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 192.168.0.250 for SIP
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1/sipSPI_detect_media_loop: Number of media loops detected - 0
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec No Codec
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 96
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/critical/1/sipSPICheckDynPayloadUse: Dynamic payload(101) could not be reserved as its in use by other codec
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/critical/1/sipSPIUpdateDynamicPT: Requested payload-Type (101) is reserved by another application
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 98
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 101
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIAllocateFreeDynamicPT: Allocating free Dynamic Payload : 98 for Codec:
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/1/sipSPIDoAudioNegotiation: Codec (g711alaw) Negotiation Successful on Static Payload for m-line 1
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/1/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/8/sipSPISetFaxFlags: FAX_PASSTHROUGH = 0, END_FAX_PASSTHROUGH = 0
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sipSPIDoDTMFRelayNegotiation: m-line index 1
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 98
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/1/sipSPIReserveRtpNtePayload: Reserved the payload type 101 for RTP-NTE
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/32/sipSPIDoDTMFRelayNegotiation: RTP-NTE DTMF relay option
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events.
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 8
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 0
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 101
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: encname is telephone-event
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/8209/sip_sdp_get_modem_relay_cap_params: mer_dtmf_found 0
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/info/8209/sip_sdp_get_modem_relay_cap_params: V150 NSE payload = 0, SSE payload = 0, SPRT payload=0
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/critical/8193/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/1/sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1
payload_type=8, codec_bytes=160, codec=g711alaw, dtmf_relay=rtp-nte
stream_type=voice+dtmf (1), dest_ip_address=15.236.40.103, dest_port=4004
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/sipSPI_Check_If_ICE_Needed: CANDIDATE attribute, level 1not found.
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/4096/ccsip_offer_ans_handle_rcvd_sdp:
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/4096/ccsip_offer_ans_is_invite_offer_valid: TRUE
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/4096/ccsip_offer_ans_common_offer_rcvd_hdlr:
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/4096/ccsip_iwf_handle_network_event:
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/4096/sip_iwf_def_ed_rcvd_sdp_hdlr:
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sipSPIAssignStreamCallId: Assigning streamid 299 to stream 1. Old streamid = -1
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sipSPI_initialize_audio_channelInfo_from_sdp: stream_callid = 299, peer_stream_callid = -1
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1025/sipSPI_initialize_audio_channelInfo_from_sdp: Populating Address in channelInfo
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sipSPI_initialize_audio_channelInfo_from_sdp: Media dest addr for stream 1='15.236.40.103', port=4004
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Processing Media line = 1; Codec = g711alaw; PType = 8
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1/codec_found: Codec to be matched: g711alaw(6)
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1/codec_found: Match Found at index 1.
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Add Channel for Codec g711alaw
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Media negotiation done for m-line=1: negotiated_ptime=0, negotiated_codec_bytes=160
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sipSPI_ipip_update_codec_params_in_stream_channelInfo: Adding negotiated codec g711alaw ptype 8 time 20, bytes 160 as channel 0 mline 1 ss 1
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/131073/sipSPIBwCacCalcAudioBandwidth: calculated audio bandwidth : codec g711alaw bw 64000
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Processing Media line = 1; Codec = g711ulaw; PType = 0
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1/codec_found: Codec to be matched: g711ulaw(5)
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1/codec_found: Match Found at index 0.
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Add Channel for Codec g711ulaw
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_ptime_to_codec_bytes: Values :Codec: g711ulaw ptime :0, codecbytes: 0
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sipSPI_ipip_GetCodecBytePtime: Codec bytes 0, use default packet rate 160
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sipSPI_ipip_update_codec_params_in_stream_channelInfo: Adding negotiated codec g711ulaw ptype 0 time 0, bytes 160 as channel 1 mline 1 ss 1
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/131073/sipSPIBwCacCalcAudioBandwidth: calculated audio bandwidth : codec g711ulaw bw 64000
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/32/sipSPI_ipip_upd_2833_dtmf_params: setting ipip_caps DTMF to RFC2833: callid = 299, dtmf = 6
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Processing Media line = 1; Codec = No Codec ; PType = 101
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/16/sipSPIGetPreferredSrtpSuiteForBw: Return : 0
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/verbose/16/sipSPIBwCacGetSrtpOverhead: Return: 0
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/4097/sipSPI_ipip_calculate_channel_count: Number of streams to report = 1
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/critical/1/sip_iwf_sip_copy_sdp_to_stream_channelInfo: Not updating call entry.
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/1/sip_iwf_def_ed_rcvd_sdp_hdlr: Setting media_direction to 4
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/131072/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
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/131072/sipSPIBwCacUpdateInterfaceBw: bwcac acquiring interface GigabitEthernet0/0 bw 80
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/131072/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (initial offer) accounted bw set to 80000
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/33/sipSPIHandleInviteMedia:
Negotiated Codec : g711alaw, 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

*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/133120/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 1410
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/1/sipSPIBwCacIsInterfaceBwAvailable: bwcac interface bw threshold not configured
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/131072/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 80000 bps
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/info/131073/sipSPIDoQoSNegotiationWithMediaLine: QOS negotiation for mline_index 1
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/131072/sipSPIDoStreamQoSNegotiation: Best effort
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/notify/131072/sipSPICanSetFallbackFlag: Local Fallback is not active
*Sep 21 13:30:13.222: //299/5B3558E78288/SIP/Info/verbose/1/sipSPIUpdateSrcSdpFixedPart: Reserving rtp port for stream 1, src_port=16708
*Sep 21 13:30:13.222: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/info/1/sipSPIUpdateSrcSdpVariablePart: Setting stream 1 portnum to 16708
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/notify/1/sipSPIUpdateSrcSdpVariablePart: SIP update src sdp, negoitated codec 6, payload type 8
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/notify/32/sipSPIUpdateSrcSdpVariablePart: Negotiated method of dtmf relayand pyld: 6 101

*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/32/sipSPIUpdateSrcSdpVariablePart: max_event 16
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPIAddBillingInfoToCcb: sipCallId for billing records = 82654f2428964fadb49a3779b0a6e4ee
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/16384/sipSPI_ipip_AddInvDataToTDContainer: req_uri = sip:141@onesixty.asuscomm.com
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/16384/sipSPI_ipip_AddInvDataToTDContainer: to = <sip:141@onesixty.asuscomm.com>
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/16384/ccsip_api_call_setup_ind: retVal = 0

*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/critical/10240/sipSPI_ipip_ExtractPassthruCopyListHdrsFromSipContainer: Unsupported header passthru is not configured and no headers are present in hdr_hash_queue
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/critical/8192/sipSPI_ipip_IsContentPassthruEnabled: - 0
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/notify/8192/sipSPI_ipip_ExtractPassthruContentFromSipContainer: Passthru Content Not Enabled
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/critical/4096/ccsip_api_call_setup_ind: Unable to add unsupp headers to container
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPICopyAuthenticationHeadersToContainer: sipAuthInfo Initialized
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPICopyAuthenticationHeadersToContainer: Set Authentication Tag to Container
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/4096/ccsip_api_call_setup_ind: Set Protocol information
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/4096/ccsip_ipip_media_service_get_event_data: Event id = 32
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/notify/8192/sipSPI_IsCustomSDPPassthruConfigured: FALSE
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/info/1/ccsip_api_call_setup_ind: Setting media_direction to 4
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPI_ipip_store_channel_info: Store channelInfo in CallInfo
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/32/sipSPI_ipip_store_channel_info: dtmf negotiation done, storing negotiated dtmf = 6,
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/2048/sipSPI_ipip_store_config_info: Setting mid_call_config_info = 0x0 for callid = 299
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/4097/sipSPI_ipip_calculate_channel_count: Number of streams to report = 1
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/1/sipSPI_ipip_store_channel_info: Setting CHANNEL_COUNT = 1 for callid 299
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/67584/sipSPIShrlCall: Check peer: 1410 for Shared-Line call, callid: 299
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/info/8192/ccsip_set_bearer_capability: Bearer Capability: Speech (0x00)
*Sep 21 13:30:13.226: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentQSIG: No QSIG Body found in inbound container
*Sep 21 13:30:13.226: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentQ931: No RawMsg Body found in inbound container
*Sep 21 13:30:13.226: //-1/xxxxxxxxxxxx/SIP/Info/critical/12288/sipSPICreateNewRawMsg: No Data to form The Raw Message

*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/info/4096/sipSPIContinueNewMsgInvite: ccsip_api_call_setup_ind returned: SIP_SUCCESS
*Sep 21 13:30:13.226: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPIUaddccCallIdToTable: Adding call id 12B to table
*Sep 21 13:30:13.234: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x229E2860
*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x229E2860 with refCount = 1
*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2101B8E4 with refCount = 1
*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [15.236.40.103]:60095, local_address:[192.168.0.250]
*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x229E2860
*Sep 21 13:30:14.466: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPISendInviteResponse: Dialog State: [0]
*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x229E2860 with refCount = 1
*Sep 21 13:30:14.466: //299/5B3558E78288/SIP/Info/info/4096/sipSPISendInviteResponse: Associated container=0x22864778 to Invite Response 404
*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
*Sep 21 13:30:14.466: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:14.466: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:141@onesixty.asuscomm.com SIP/2.0
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
Max-Forwards: 70
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>
Contact: <sip:141@15.236.40.103:60095;ob>
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: MicroSIP/3.21.2
Content-Type: application/sdp
Content-Length: 343

v=0
o=- 3872756853 3872756853 IN IP4 15.236.40.103
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4004 RTP/AVP 8 0 101
c=IN IP4 15.236.40.103
b=TIAS:64000
a=rtcp:4005 IN IP4 15.236.40.103
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:1761956497 cname:206e52b339743cb7

*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Sep 21 13:30:14.466: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPIFindCcbUASReqTable: *****CCB found in UAS Request table. ccb=0x3F967460
*Sep 21 13:30:14.466: //299/5B3558E78288/SIP/Info/info/4096/sippmh_cmp_tags: An Invite request retransmission is received in the COMPLETED state.
*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x2101B8E4) counter, current msg->refCount = 2
*Sep 21 13:30:14.466: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Sep 21 13:30:14.466: //299/5B3558E78288/SIP/Info/info/4096/sipSPISetCallingInfo: calling host comes from From: 192.168.0.250
*Sep 21 13:30:14.466: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with FROM header data
*Sep 21 13:30:14.466: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPISendInviteResponse: Dialog State: [0]
*Sep 21 13:30:14.470: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x21029800 with refCount = 1
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: Generate uuid
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: generated uuid - 6390e74b50b15400aefd80bd29574360
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/notify/8192/sipSPIAddSessionID: localUUID - 6390e74b50b15400aefd80bd29574360 remoteUUID - 5e18cc70f8b35932929dc0ca309e27df
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/notify/8192/sipSPIAddSessionID: Session-ID header 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/info/8192/Session-Timer/sipSTSLMain:
SE: 1800;refresher:none peer refresher:none, flags:1, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:1800, Configured Min-SE:1800
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 3 event
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/info/4096/sipSPISendInviteResponse: Associated container=0x22864250 to Invite Response 404
*Sep 21 13:30:14.470: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:14.470: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:14.470: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x2101B8E4) counter, current msg->refCount = 1
*Sep 21 13:30:14.470: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2101B8E4
*Sep 21 13:30:14.490: //299/5B3558E78288/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>;tag=154BF70-1F40
Date: Wed, 21 Sep 2022 13:30:13 GMT
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.7.3.M8
Reason: Q.850;cause=1
Session-ID: 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
Content-Length: 0

*Sep 21 13:30:14.490: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x229E2860
*Sep 21 13:30:14.490: //299/5B3558E78288/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>;tag=154BF70-1F40
Date: Wed, 21 Sep 2022 13:30:14 GMT
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow-Events: telephone-event
Reason: Q.850;cause=1
Server: Cisco-SIPGateway/IOS-15.7.3.M8
Session-ID: 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
Content-Length: 0

*Sep 21 13:30:14.490: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x21029800
*Sep 21 13:30:14.862: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x229E2860 with refCount = 1
*Sep 21 13:30:14.862: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2101B8E4 with refCount = 1
*Sep 21 13:30:14.862: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [15.236.40.103]:60095, local_address:[192.168.0.250]
*Sep 21 13:30:14.862: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x229E2860
*Sep 21 13:30:14.862: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Sep 21 13:30:14.862: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:141@onesixty.asuscomm.com SIP/2.0
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
Max-Forwards: 70
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>
Contact: <sip:141@15.236.40.103:60095;ob>
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: MicroSIP/3.21.2
Content-Type: application/sdp
Content-Length: 343

v=0
o=- 3872756853 3872756853 IN IP4 15.236.40.103
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4004 RTP/AVP 8 0 101
c=IN IP4 15.236.40.103
b=TIAS:64000
a=rtcp:4005 IN IP4 15.236.40.103
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:1761956497 cname:206e52b339743cb7

*Sep 21 13:30:14.862: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Sep 21 13:30:14.862: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPIFindCcbUASReqTable: *****CCB found in UAS Request table. ccb=0x3F967460
*Sep 21 13:30:14.862: //299/5B3558E78288/SIP/Info/info/4096/sippmh_cmp_tags: An Invite request retransmission is received in the COMPLETED state.
*Sep 21 13:30:14.862: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x2101B8E4) counter, current msg->refCount = 2
*Sep 21 13:30:14.862: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Sep 21 13:30:14.862: //299/5B3558E78288/SIP/Info/info/4096/sipSPISetCallingInfo: calling host comes from From: 192.168.0.250
*Sep 21 13:30:14.866: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x229E2860
*Sep 21 13:30:16.150: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPISendInviteResponse: Dialog State: [0]
*Sep 21 13:30:16.150: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x229E2860 with refCount = 1
*Sep 21 13:30:16.150: //299/5B3558E78288/SIP/Info/info/4096/sipSPISendInviteResponse: Associated container=0x22864778 to Invite Response 404
*Sep 21 13:30:16.150: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
*Sep 21 13:30:16.150: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:16.150: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:16.150: //299/5B3558E78288/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>;tag=154BF70-1F40
Date: Wed, 21 Sep 2022 13:30:14 GMT
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.7.3.M8
Reason: Q.850;cause=1
Session-ID: 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
Content-Length: 0

*Sep 21 13:30:16.150: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x229E2860
*Sep 21 13:30:16.838: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2101B8E4 with refCount = 1
*Sep 21 13:30:16.838: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22A8CB10 with refCount = 1
*Sep 21 13:30:16.838: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [15.236.40.103]:60095, local_address:[192.168.0.250]
*Sep 21 13:30:16.838: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2101B8E4
*Sep 21 13:30:16.838: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Sep 21 13:30:16.838: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:141@onesixty.asuscomm.com SIP/2.0
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
Max-Forwards: 70
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>
Contact: <sip:141@15.236.40.103:60095;ob>
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: MicroSIP/3.21.2
Content-Type: application/sdp
Content-Length: 343

v=0
o=- 3872756853 3872756853 IN IP4 15.236.40.103
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4004 RTP/AVP 8 0 101
c=IN IP4 15.236.40.103
b=TIAS:64000
a=rtcp:4005 IN IP4 15.236.40.103
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:1761956497 cname:206e52b339743cb7

*Sep 21 13:30:16.838: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Sep 21 13:30:16.838: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPIFindCcbUASReqTable: *****CCB found in UAS Request table. ccb=0x3F967460
*Sep 21 13:30:16.838: //299/5B3558E78288/SIP/Info/info/4096/sippmh_cmp_tags: An Invite request retransmission is received in the COMPLETED state.
*Sep 21 13:30:16.838: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x22A8CB10) counter, current msg->refCount = 2
*Sep 21 13:30:16.838: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Sep 21 13:30:16.838: //299/5B3558E78288/SIP/Info/info/4096/sipSPISetCallingInfo: calling host comes from From: 192.168.0.250
*Sep 21 13:30:16.838: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with FROM header data
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPISendInviteResponse: Dialog State: [0]
*Sep 21 13:30:16.842: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2101B8E4 with refCount = 1
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: Generate uuid
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: generated uuid - 6390e74b50b15400aefd80bd29574360
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/notify/8192/sipSPIAddSessionID: localUUID - 6390e74b50b15400aefd80bd29574360 remoteUUID - 5e18cc70f8b35932929dc0ca309e27df
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/notify/8192/sipSPIAddSessionID: Session-ID header 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/info/8192/Session-Timer/sipSTSLMain:
SE: 1800;refresher:none peer refresher:none, flags:1, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:1800, Configured Min-SE:1800
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 3 event
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/info/4096/sipSPISendInviteResponse: Associated container=0x22864EB0 to Invite Response 404
*Sep 21 13:30:16.842: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:16.842: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x22A8CB10) counter, current msg->refCount = 1
*Sep 21 13:30:16.842: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22A8CB10
*Sep 21 13:30:16.842: //299/5B3558E78288/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>;tag=154BF70-1F40
Date: Wed, 21 Sep 2022 13:30:16 GMT
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow-Events: telephone-event
Reason: Q.850;cause=1
Server: Cisco-SIPGateway/IOS-15.7.3.M8
Session-ID: 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
Content-Length: 0

*Sep 21 13:30:16.842: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2101B8E4
*Sep 21 13:30:17.618: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPISendInviteResponse: Dialog State: [0]
*Sep 21 13:30:17.618: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2101B8E4 with refCount = 1
*Sep 21 13:30:17.618: //299/5B3558E78288/SIP/Info/info/4096/sipSPISendInviteResponse: Associated container=0x22864778 to Invite Response 404
*Sep 21 13:30:17.618: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
*Sep 21 13:30:17.618: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:17.618: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:17.618: //299/5B3558E78288/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>;tag=154BF70-1F40
Date: Wed, 21 Sep 2022 13:30:16 GMT
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.7.3.M8
Reason: Q.850;cause=1
Session-ID: 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
Content-Length: 0

*Sep 21 13:30:17.618: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2101B8E4
*Sep 21 13:30:20.746: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2101B8E4 with refCount = 1
*Sep 21 13:30:20.746: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22A8CB10 with refCount = 1
*Sep 21 13:30:20.746: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [15.236.40.103]:60095, local_address:[192.168.0.250]
*Sep 21 13:30:20.746: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2101B8E4
*Sep 21 13:30:20.746: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Sep 21 13:30:20.750: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:141@onesixty.asuscomm.com SIP/2.0
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
Max-Forwards: 70
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>
Contact: <sip:141@15.236.40.103:60095;ob>
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: MicroSIP/3.21.2
Content-Type: application/sdp
Content-Length: 343

v=0
o=- 3872756853 3872756853 IN IP4 15.236.40.103
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4004 RTP/AVP 8 0 101
c=IN IP4 15.236.40.103
b=TIAS:64000
a=rtcp:4005 IN IP4 15.236.40.103
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:1761956497 cname:206e52b339743cb7

*Sep 21 13:30:20.750: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPIFindCcbUASReqTable: *****CCB found in UAS Request table. ccb=0x3F967460
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/info/4096/sippmh_cmp_tags: An Invite request retransmission is received in the COMPLETED state.
*Sep 21 13:30:20.750: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x22A8CB10) counter, current msg->refCount = 2
*Sep 21 13:30:20.750: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/info/4096/sipSPISetCallingInfo: calling host comes from From: 192.168.0.250
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with FROM header data
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPISendInviteResponse: Dialog State: [0]
*Sep 21 13:30:20.750: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2101B8E4 with refCount = 1
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: Generate uuid
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/info/8192/sipSPIGenerateSessionUUID: generated uuid - 6390e74b50b15400aefd80bd29574360
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/notify/8192/sipSPIAddSessionID: localUUID - 6390e74b50b15400aefd80bd29574360 remoteUUID - 5e18cc70f8b35932929dc0ca309e27df
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/notify/8192/sipSPIAddSessionID: Session-ID header 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/info/8192/Session-Timer/sipSTSLMain:
SE: 1800;refresher:none peer refresher:none, flags:1, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:1800, Configured Min-SE:1800
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 3 event
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPIPushSubsqTransMsgContainerIntoHolder: Response Container Holder is above threshold...trimming
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/info/4096/sipSPISendInviteResponse: Associated container=0x22866140 to Invite Response 404
*Sep 21 13:30:20.750: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:20.750: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x22A8CB10) counter, current msg->refCount = 1
*Sep 21 13:30:20.750: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22A8CB10
*Sep 21 13:30:20.750: //299/5B3558E78288/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>;tag=154BF70-1F40
Date: Wed, 21 Sep 2022 13:30:20 GMT
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow-Events: telephone-event
Reason: Q.850;cause=1
Server: Cisco-SIPGateway/IOS-15.7.3.M8
Session-ID: 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
Content-Length: 0

*Sep 21 13:30:20.750: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2101B8E4
*Sep 21 13:30:21.466: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPISendInviteResponse: Dialog State: [0]
*Sep 21 13:30:21.466: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2101B8E4 with refCount = 1
*Sep 21 13:30:21.466: //299/5B3558E78288/SIP/Info/info/4096/sipSPISendInviteResponse: Associated container=0x22864778 to Invite Response 404
*Sep 21 13:30:21.466: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
*Sep 21 13:30:21.466: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:21.466: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:21.466: //299/5B3558E78288/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>;tag=154BF70-1F40
Date: Wed, 21 Sep 2022 13:30:20 GMT
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.7.3.M8
Reason: Q.850;cause=1
Session-ID: 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
Content-Length: 0

*Sep 21 13:30:25.466: //299/5B3558E78288/SIP/Info/verbose/4096/sipSPISendInviteResponse: Dialog State: [0]
*Sep 21 13:30:25.466: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2101B8E4 with refCount = 1
*Sep 21 13:30:25.466: //299/5B3558E78288/SIP/Info/info/4096/sipSPISendInviteResponse: Associated container=0x22864778 to Invite Response 404
*Sep 21 13:30:25.466: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
*Sep 21 13:30:25.466: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:25.466: //299/5B3558E78288/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
*Sep 21 13:30:25.466: //299/5B3558E78288/SIP/Msg/ccsipDisplayMsg:
Sent:

All possible debugging has been turned off
VoiceGw#SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 15.236.40.103:60095;branch=z9hG4bKPja649580951c64be98333ddd85caa285b
From: <sip:141@192.168.0.250>;tag=2c1fc00c512e4e729e23626db14c8ac8
To: <sip:141@onesixty.asuscomm.com>;tag=154BF70-1F40
Date: Wed, 21 Sep 2022 13:30:20 GMT
Call-ID: 82654f2428964fadb49a3779b0a6e4ee
CSeq: 21913 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.7.3.M8
Reason: Q.850;cause=1
Session-ID: 5e18cc70f8b35932929dc0ca309e27df;remote=6390e74b50b15400aefd80bd29574360
Content-Length: 0

*Sep 21 13:30:25.466: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2101B8E4

Roger Kallberg · ‎09-21-2022

As @b.winter wrote there are normally a bunch more values in the SIP dialog that you'd want to modify in a NAT situation other than the Via header. That said based on the SIP profile test tool this is what should be happening to your SIP dialog based on the SIP profile that you have.

One small observation on your SIP profile is that you have via in all lower cap, IOS might be picky and want that to be Via as that is what the field is named.

To give you an example of modification that might be needed please have a look at a profile we currently use for a Webex CC integration.

voice class sip-profiles 10
 rule 5 request ANY sip-header From modify "<internal int IP>" "<external int IP>" 
 rule 10 request ANY sip-header Contact modify "<sip:(.*)@<external int IP>:5060>" "<sip:\1@<public IP>:5060>" 
 rule 20 response ANY sip-header Contact modify "<sip:(.*)@<external int IP>:5060>" "<sip:\1@<public IP>:5060>" 
 rule 30 request ANY sip-header Via modify "SIP(.*) <external int IP>(.*)" "SIP\1 <public IP>\2" 
 rule 40 request INVITE sip-header Requested-By modify "sip:<external int IP>>" "sip:<public IP>>" 
 rule 50 request ANY sdp-header Session-Owner modify "<external int IP>" "<public IP>" 
 rule 60 response ANY sdp-header Session-Owner modify "<external int IP>" "<public IP>" 
 rule 70 request ANY sdp-header Connection-Info modify "<external int IP>" "<public IP>" 
 rule 80 response ANY sdp-header Connection-Info modify "<external int IP>" "<public IP>" 
 rule 90 request ANY sdp-header Audio-Connection-Info modify "<external int IP>" "<public IP>" 
 rule 100 response ANY sdp-header Audio-Connection-Info modify "<external int IP>" "<public IP>" 
!
voice class sip-profiles 200
 rule 10 request ANY sip-header Via modify "SIP(.*) <external int IP>(.*)" "SIP\1 <public IP>\2" 
 rule 20 request OPTIONS sip-header From modify "<sip:<external int IP>" "<sip:<public IP>" 
 rule 30 request ANY sip-header To modify "<sip:<external int IP>" "<sip:<public IP>" 
 rule 40 request OPTIONS sip-header Contact modify "<sip:<external int IP>" "<sip:<public IP>" 
 rule 50 response ANY sdp-header Connection-Info modify "<external int IP>" "<public IP>" 
 rule 60 response ANY sdp-header Audio-Connection-Info modify "<external int IP>" "<public IP>" 
!
voice class sip-profiles 100
 rule 10 request OPTIONS sip-header SIP-Req-URI modify "<public IP>" "<external int IP>" 
 rule 20 request ANY sip-header To modify "<sip:<public IP>" "<sip:<external int IP>" 
!
voice class uri WEBEX sip
 host ipv4:<VIA address 1>
 host ipv4:<VIA address 2>
!
voice class sip-options-keepalive 200
 description ** Webex CC - Options-Ping **
 sip-profiles 200
!
voice service voip
 sip
  sip-profiles inbound
!
dial-peer voice 100 voip
 description Incoming Dial-Peer from Webex CC
 incoming uri via WEBEX
 voice-class sip profiles 100 inbound
!
dial-peer voice 110 voip
 description Outbound Dial-Peer for calls to Webex CC
 voice-class sip profiles 10

One additional small difference between your shared configuration and what I shared is that we do the inbound SIP profile on the inbound dial peer from the Webex CC as compared to what you do on a global level. I would recommend you to not do that on that level, but do it on the inbound dial peer instead.

For detailed information on dial peers and call routing in IOS please have a look at this document. In Depth Explanation of Cisco IOS and IOS-XE Call Routing

Hesham Mohamed El Sayed · ‎09-21-2022

Thanks

i didnt even reach the point where my sdp needs updated public ip , just TRYING message cube is sending i cannot see it reaching the UAC and i dont know why

Roger Kallberg · ‎09-21-2022

I'm sorry but I do not quite understand what you mean. Would you mind to clarify?

Hesham Mohamed El Sayed · ‎09-21-2022

i manually entered public IP in UAC , and the invite came with public ip as suppose too , but when cube sent trying or ringing , i recieved nothing on UAC

Roger Kallberg · ‎09-21-2022

I'm sorry but I do not quite understand what you mean. Would you mind to elaborate?

Hesham Mohamed El Sayed · ‎09-21-2022

i know destination 141 is not reachable , but even trying message is not sent to the public ip , i have 141 configured previously and when it was ringing , the cube was sending ringing to the public ip many times but nothing really recieved