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

CME Problem with Jabber calling any 7975 (SIP)...

choevenaars
Level 1
Level 1

‎09-10-2020 02:31 PM - edited ‎09-10-2020 03:49 PM

Hardware: ISR4331 with IOS Version 16.12.4, RELEASE SOFTWARE (fc5)

CME 12.5 

I have a simple test environment with SIP only and have successfully setup the Jabber client for Windows.

The Jabber phone can call my test 7821 and 8945 phones (SIP) with no problem.

The 7975s can call Jabber, again NP. 

Problem: Jabber Client cannot call 7975s ? When you dial the ext it disconnects instantly. 

My Attempts: Moved the DNs around and only common issue appears to be the model of phone. 

 

CME Config:

ip host _sip_tcp.domain.ca srv 0 1 5060 cme.domain.ca
ip host _sip_udp.domain.ca srv 0 1 5060 cme.domain.ca
ip host _sips_tcp.domain.ca srv 0 1 5060 cme.domain.ca
ip host _cisco-uds._tcp.domain.ca srv 0 1 8443 cme.domain.ca
ip host uds._tcp.domain.ca srv 0 1 8443 cme.domain.ca
ip host _collab-edge._tls.domain.ca srv 0 1 8443 cme.domain.ca
ip host cme.domain.ca 172.20.11.1
ip host _cisco-phone-http.tcp.domain.ca srv 0 1 8443 cme.domain.ca
!
voice service voip
allow-connections sip to sip
sip
bind control source-interface GigabitEthernet0/0/0.13
bind media source-interface GigabitEthernet0/0/0.13
registrar server expires max 600 min 60
!
voice register global
mode cme
source-address 172.20.13.1 port 5060
max-dn 5
max-pool 20
phone-mode phone-only
create profile sync 0026002442514737
!
voice register dn 1 (dup to 5)
number 2001 (dup to 2005)
!
voice register pool 1
id mac C472.95A8.68FE
type 7821
number 1 dn 1
codec g711ulaw
!
voice register pool 2
id mac 20BB.C0DE.3C8E
type 8945
number 1 dn 2
codec g711ulaw
!
voice register pool 3
id mac 001E.7AC3.C464
type 7975
number 1 dn 3
codec g711ulaw
!
voice register pool 4
id mac 0021.5553.8A0B
type 7975
number 1 dn 4
codec g711ulaw
!
voice register pool 5
id device-id-name J4W
type Jabber-CSF-Client
number 1 dn 5
dtmf-relay rtp-nte
username cisco password cisco
codec g711ulaw
no vad
!
interface GigabitEthernet0/0/0 (Computers - Jabber)
ip address 172.20.11.1 255.255.255.0
negotiation auto
!
interface GigabitEthernet0/0/0.13 (Phones)
encapsulation dot1Q 13
ip address 172.20.13.1 255.255.255.0
!
ip http server
ip http authentication local
ip http secure-server
ip http secure-port 8443
ip dns server

Wireshark shows a SIP INVITE - Reply is SIP/2.0 --> 500 Internal Server Error (Reason protocols: Q.820; cause=127)

 

SIP Call messages tracing is enabled
SIP Call error tracing is enabled
SIP Call info tracing is enabled
voip ccapi inout debugging is on
CME-Pod1#
Sep 10 23:30:09.807: //581/53AA65C8830D/SIP/Info/verbose/1/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Sep 10 23:30:09.807: //581/53AA65C8830D/SIP/Info/info/8192/sipSPIStopOverlapInfoTimer: Stopping Overlap Info Timer
Sep 10 23:30:09.808: //581/53AA65C8830D/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x7F8480AECDD0
Sep 10 23:30:14.527: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F8489F9C740 with refCount = 1
Sep 10 23:30:14.527: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F848960E370 with refCount = 1
Sep 10 23:30:14.527: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [172.20.11.11]:5060, local_address:[172.20.13.1]
Sep 10 23:30:14.527: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F8489F9C740
Sep 10 23:30:14.528: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Sep 10 23:30:14.528: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:Received:
INVITE sip:2004@172.20.13.1;user=phone SIP/2.0
Via: SIP/2.0/UDP 172.20.11.11:5060;branch=z9hG4bK00004081
From: "WINChad" <sip:2005@172.20.13.1>;tag=000fc910f652006b0000015b-00001edb
To: <sip:2004@172.20.13.1>
Call-ID: 000fc910-f6520033-000032af-00006595@172.20.11.11
Max-Forwards: 70
Session-ID: 000076b100105000a000000fc910f652;remote=00000000000000000000000000000000
Date: Thu, 10 Sep 2020 22:30:41 GMT
CSeq: 101 INVITE
User-Agent: Cisco-CSF
Contact: <sip:15C51AD-1453@172.20.11.11:5060;transport=udp>;+u.sip!devicename.ccm.cisco.com="J4W"
Expires: 180
Accept: application/sdp
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE,INFO
Remote-Party-ID: "WINChad" <sip:2005@172.20.13.1>;party=calling;id-type=subscriber;privacy=off;screen=yes
Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecode
s,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1
Allow-Events: kpml,dialog
Recv-Info: conference
Recv-Info: x-cisco-conference
Content-Length: 869
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 11137 0 IN IP4 172.20.11.11
s=SIP Call
b=AS:4000
t=0 0
a=cisco-mari:v1
a=cisco-mari-rate
m=audio 17966 RTP/AVP 0 114 9 104 105 8 18 111 101
c=IN IP4 172.20.11.11
a=rtpmap:0 PCMU/8000
a=rtpmap:114 opus/48000/2
a=rtpmap:9 G722/8000
a=rtpmap:104 G7221/16000
a=fmtp:104 bitrate=32000
a=rtpmap:105 G7221/16000
a=fmtp:105 bitrate=24000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:111 x-ulpfecuc/8000
a=extmap:14/sendrecv http://protocols.cisco.com/timestamp#100us
a=fmtp:111 max_esel=1420;m=8;max_n=32;FEC_ORDER=FEC_SRTP
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=sendrecv
m=application 54892 UDP/UDT/IX *
c=IN IP4 172.20.11.11
a=ixmap:11 xccp
a=setup:actpass
a=fingerprint:sha-256 DD:58:A8:CB:2F:D8:A8:C6:BE:B0:32:28:3B:44:30:2A:A9:FC:EA:F4:23:CB:44:73:61:9D:81:8E:20:BD:AE:CB
a=sendrecv

Sep 10 23:30:14.528: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Sep 10 23:30:14.529: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIIpTrustSilentDiscard: sipSPIIpTrustSilentDiscard: called for method [102]
Sep 10 23:30:14.529: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x7F8480AECDD0) with key=[523] to table
Sep 10 23:30:14.529: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Sep 10 23:30:14.529: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Sep 10 23:30:14.529: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Sep 10 23:30:14.529: //-1/000000000000/SIP/Info/verbose/4097/ccsip_platform_init_ccb: Initialising rtp session queue
Sep 10 23:30:14.529: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Sep 10 23:30:14.529: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:14.529: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:14.530: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 172.20.13.1 for SIP
Sep 10 23:30:14.530: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 172.20.13.1 for SIP
Sep 10 23:30:14.530: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 172.20.13.1
Sep 10 23:30:14.530: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 0
Sep 10 23:30:14.530: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 172.20.13.1
Sep 10 23:30:14.530: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone EDT to SIP default timezone =GMT
Sep 10 23:30:14.530: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Sep 10 23:30:14.531: //-1/699165018315/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:14.531: //-1/699165018315/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:14.531: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 172.20.13.1 for SIP
Sep 10 23:30:14.531: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 172.20.13.1 for SIP
Sep 10 23:30:14.531: //-1/699165018315/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 172.20.13.1
Sep 10 23:30:14.531: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 0
Sep 10 23:30:14.531: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 172.20.13.1
Sep 10 23:30:14.531: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7F848960E370) counter, current msg->ref Count = 2
Sep 10 23:30:14.531: //-1/xxxxxxxxxxxx/SIP/Error/httpish_msg_free:Freeing NULL pointer!
Sep 10 23:30:14.531: //-1/699165018315/SIP/Info/verbose/4096/sipSPIAssignCcbLastRequest: Current ccb(0x7F8480AECDD0)->last_request = 0x7F848960E370, refCount = 2
Sep 10 23:30:14.532: //-1/699165018315/SIP/Info/info/8192/sipSPIExtractSessionID: Parsed localuuid - 000076b100105000a000000fc910f652 remoteuuid - 00000000000000000000000000000000
Sep 10 23:30:14.532: //-1/699165018315/SIP/Info/notify/8192/sipSPI_Add_SessionIDtoTDContainer: localUUID - 000076b100105000a000000fc910f652remoteUUID - 00000000000000000000000000000000
Sep 10 23:30:14.532: //-1/699165018315/SIP/Info/info/8192/sipSPIProcessSessionID: SessionID header is present- 000076b100105000a000000fc910f652
Sep 10 23:30:14.532: //-1/xxxxxxxxxxxx/SIP/Info/info/2048/sipSPICheckIpip: VOIP dialpeer (peer=0x55F396BE3464) found for sip_user: 2004
Sep 10 23:30:14.532: //-1/699165018315/SIP/Info/info/2048/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
Sep 10 23:30:14.532: //-1/699165018315/SIP/Info/info/4096/sipSPISetCallingInfo: calling host comes from From: 172.20.13.1
Sep 10 23:30:14.533: //-1/699165018315/SIP/Info/verbose/32768/ccsip_ipip_media_forking_copy_prev_best_rpid: MF: This is best rpid..
Sep 10 23:30:14.533: //-1/699165018315/SIP/Info/verbose/4096/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with RPID header data
Sep 10 23:30:14.533: //-1/699165018315/SIP/Info/verbose/4096/sipSPISetInfoFromRpid: calling host comes from rpid 172.20.13.1
Sep 10 23:30:14.533: //-1/699165018315/SIP/Info/info/4096/sipSPISetInfoFromRpid: Received current remote name: WINChad, current remote number: 2005
Sep 10 23:30:14.533: //-1/699165018315/SIP/Info/info/4096/sipSPISetInfoFromRpid: Received ;screen=yes ;privacy=off -> Setting Octet3A 0x81, extended_privacy 0x00
Sep 10 23:30:14.533: //-1/699165018315/SIP/Info/info/2048/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
Sep 10 23:30:14.533: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentGTD: No GTD found in inbound container
Sep 10 23:30:14.533: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentCSTA: No CSTA found in inbound container
Sep 10 23:30:14.533: //-1/699165018315/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x7F8480AECDD0 key=000fc910-f6520033-000032af-00006595@172.20.11.112004 balance 0
Sep 10 23:30:14.533: //-1/699165018315/SIP/Info/info/8192/sipSPIMatchSrcIpGroup: Match not found on carrier id
Sep 10 23:30:14.534: //-1/699165018315/SIP/Info/critical/8192/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 2004
Sep 10 23:30:14.534: //-1/699165018315/SIP/Info/critical/8192/sipSPIMatchSrcIpGroup: Match not found on destination pattern: 2005
Sep 10 23:30:14.534: //-1/699165018315/SIP/Info/info/12288/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name WINChad, number 2005, Calling oct3 0x00, oct_3a 0x81, Called number 2004
Sep 10 23:30:14.534: //-1/699165018315/SIP/Info/verbose/1024/sipSPIGetViaHostInURLFormat: VIA URL:sip:172.20.11.11:5060, Host:172.20.11.11
Sep 10 23:30:14.535: //-1/699165018315/SIP/Info/verbose/67584/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : 2005
Sep 10 23:30:14.535: //-1/699165018315/SIP/Info/critical/1024/sipSPIGetFromCalledPartyId: P-Called-Party-ID header not found
Sep 10 23:30:14.535: //-1/699165018315/SIP/Info/critical/1024/sipSPIGetPeerByCalledPartyId: P-Called-Party-ID not found or parse error
Sep 10 23:30:14.535: //-1/699165018315/SIP/Info/critical/10240/sipSPIGetCallConfig: No match found for P-Called-Party-ID
Sep 10 23:30:14.535: //-1/699165018315/SIP/Info/info/65536/ccsip_sipline_set_vrf: Phone number 2005 from ccb calling number
Sep 10 23:30:14.535: //-1/xxxxxxxxxxxx/SIP/Info/info/65536/ccsip_sipline_set_vrf: Obtained phone tag (5) from Dial-peer
Sep 10 23:30:14.535: //-1/xxxxxxxxxxxx/SIP/Info/info/65536/ccsip_sipline_set_vrf: Got vrf 0 for phone tag 5
Sep 10 23:30:14.535: //-1/699165018315/SIP/Info/info/8192/sipSPIGetCallConfig: VRF id = 0
Sep 10 23:30:14.535: //-1/699165018315/SIP/Info/info/2048/sipSPIGetCallConfig: Peer tag 40004 matched for incoming call
Sep 10 23:30:14.535: //-1/699165018315/SIP/Info/verbose/1024/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
Sep 10 23:30:14.535: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Sep 10 23:30:14.536: //-1/699165018315/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:14.536: //-1/699165018315/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:14.536: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 172.20.13.1 for SIP
Sep 10 23:30:14.536: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 172.20.13.1 for SIP
Sep 10 23:30:14.536: //-1/699165018315/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 172.20.13.1
Sep 10 23:30:14.536: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 0
Sep 10 23:30:14.536: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 172.20.13.1
Sep 10 23:30:14.536: //-1/699165018315/SIP/Info/info/2048/sipSPISetAudioForcedConfig: audio forced config is set to FALSE
Sep 10 23:30:14.536: //-1/699165018315/SIP/Info/verbose/1024/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/verbose/2048/sipSPIGetCallConfig: Media Antitrombone disabled
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/notify/65536/sipSPISetMediaFlowMode: Forcing the flow mode to FLOW-THROUGH for SIP line
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/info/8192/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/info/2304/sipSPISetMediaFlowMode: xcoder high-density disabled
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/info/8192/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/verbose/4/update_video_params_from_config: Checking Video Type Rate=-1 video_codec_allowed=1F
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/verbose/12288/sipSPIGetModemInfoPerCall: peer_callID=0
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/critical/32768/ccsip_ipip_media_forking_update_preferred_codec: MF: Not a Forked SIP leg..
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/info/8704/sipSPIGetCallConfig: Incoming: No defer BYE for last call stats
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/info/34816/ccsip_ipip_media_forking_anchor_leg_config: MF: en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
Sep 10 23:30:14.537: //-1/699165018315/SIP/Info/info/34816/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer has no media class recorder.
Sep 10 23:30:14.538: //-1/699165018315/SIP/Info/info/32768/ccsip_ipip_media_forking_intra_frame_request_config: MF: FIR en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
Sep 10 23:30:14.538: //-1/699165018315/SIP/Info/info/32768/ccsip_ipip_media_forking_get_forked_leg_config: MF: This leg is not forked call leg.
Sep 10 23:30:14.538: //-1/699165018315/SIP/Info/verbose/1/ccsip_set_srtp_config: No Srtp configure for this leg.
Sep 10 23:30:14.538: //-1/699165018315/SIP/Info/info/8192/sipSPIGetCallConfig: Media forking disabled
Sep 10 23:30:14.538: //-1/699165018315/SIP/Info/critical/11264/ccsipInitDSCPPolicyInfo: No DSCP Profile configured, No RPH 2 DSCP Mapping and DSCP policing
Sep 10 23:30:14.538: //-1/699165018315/SIP/Info/verbose/8192/sipSPIGetCallConfig: Initilise the DSCP policy
Sep 10 23:30:14.538: //-1/699165018315/SIP/Info/verbose/8192/sipSPICheckFAAnatAssymetricOrDO2EO: Not a SIP-SIP call or not in FA mode
Sep 10 23:30:14.538: //-1/699165018315/SIP/Info/notify/8192/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Line
Sep 10 23:30:14.538: //-1/699165018315/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
Sep 10 23:30:14.538: //-1/699165018315/SIP/Info/verbose/2048/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
Sep 10 23:30:14.539: //-1/699165018315/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Sep 10 23:30:14.539: //-1/699165018315/SIP/Info/notify/10240/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
Sep 10 23:30:14.539: //-1/699165018315/SIP/Info/info/4096/sipSPIContinueNewMsgInvite: Calling name WINChad, number 2005, Calling oct3 0x00, oct_3a 0x81, ext_priv 0x00, Called number 2004, oct3 0x00
Sep 10 23:30:14.539: //-1/699165018315/SIP/Info/info/4096/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE
Sep 10 23:30:14.539: //-1/699165018315/SIP/Info/critical/10240/sipSPIValidateRequestUri: Not Enabled
Sep 10 23:30:14.539: //-1/699165018315/SIP/Info/info/65536/UpdateSIPDirectVoiceMailNumber: Direct call from SIP line: called number2004
Sep 10 23:30:14.539: //-1/699165018315/SIP/Info/info/131072/sipSPIRscmsmAvail: Value returned by check is = 0
Sep 10 23:30:14.539: //584/699165018315/SIP/Info/critical/4096/sipSPICheckOutBoundDpProvisioning: Cannot obtain the the header bitmap
Sep 10 23:30:14.539: //584/699165018315/SIP/Info/critical/8192/sipSPI_ipip_IsSDPPassthruEnabled: - 0
Sep 10 23:30:14.540: //584/699165018315/SIP/Info/info/8192/sipSPIExtractSessionID: Parsed localuuid - 000076b100105000a000000fc910f652 remoteuuid - 00000000000000000000000000000000
Sep 10 23:30:14.540: //584/699165018315/SIP/Info/notify/8192/sipSPI_Add_SessionIDtoTDContainer: localUUID - 000076b100105000a000000fc910f652remoteUUID - 00000000000000000000000000000000
Sep 10 23:30:14.540: //584/699165018315/SIP/Info/info/8192/sipSPIProcessSessionID: SessionID header is present- 000076b100105000a000000fc910f652
Sep 10 23:30:14.540: //584/699165018315/SIP/Info/notify/819/Session-Timer/sipSTSLExtractSessionExpiresHdr: Not received session expires header
Sep 10 23:30:14.540: //584/699165018315/SIP/Info/info/8192/Session-Timer/sipSTSLGetInternalSREvent: E_STSL_INITIAL_SR_REQ
Sep 10 23:30:14.540: //584/699165018315/SIP/Info/info/8192/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
Sep 10 23:30:14.540: //584/699165018315/SIP/Info/critical/1024/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container
Sep 10 23:30:14.540: //584/699165018315/SIP/Info/critical/1024/sipSPIProcessDiversionHeader: No diversion headers recvd from app container
Sep 10 23:30:14.540: //584/699165018315/SIP/Info/critical/16384/sipSPIProcessReplacesHeader: No replaces hdr found
Sep 10 23:30:14.541: //-1/xxxxxxxxxxxx/SIP/Info/verbose/1024/sipSPIGetContentCPA: No CPA found in inbound container
Sep 10 23:30:14.541: //584/699165018315/SIP/Info/verbose/1024/sipSPIProcessCPA: No x-cisco-cpa content found
SIP: Warning: Unrecognized attribute (cisco-mari)
SIP: Warning: Unrecognized attribute (cisco-mari-rate)
SIP: Warning: Unrecognized attribute (extmap)
SIP: Trying to parse unsupported attribute at media level
Sep 10 23:30:14.541: //584/699165018315/SIP/Info/verbose/1/sipSPIDoMediaNegotiation: Number of m-lines = 2
Sep 10 23:30:14.541: //584/699165018315/SIP/Info/verbose/1/sipSPIValidateConnectionAddress: Dest port = 17966
SIP: (584) Attribute mid, level 1 instance 1 not found.
Sep 10 23:30:14.542: //584/699165018315/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Sep 10 23:30:14.542: //584/699165018315/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: peer_tag=40004
Sep 10 23:30:14.542: //584/699165018315/SIP/Info/info/2048/resolve_media_ip_address_to_bind: Configuration not found for bind media source-ip
Sep 10 23:30:14.542: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Sep 10 23:30:14.542: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_media_ip_address_to_bind: VRF id = 0
Sep 10 23:30:14.542: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 172.20.13.1 for SIP
Sep 10 23:30:14.542: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 172.20.13.1 for SIP
Sep 10 23:30:14.542: //584/699165018315/SIP/Info/verbose/1/sipSPI_detect_media_loop: Number of media loops detected - 0
Sep 10 23:30:14.542: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(114) reserved for codec No Codec
Sep 10 23:30:14.542: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 96
Sep 10 23:30:14.542: //-1/xxxxxxxxxxxx/SIP/Info/critical/1/sipSPICheckDynPayloadUse: Dynamic payload(114) could not be reserved asits in use by other codec aacld
Sep 10 23:30:14.542: //-1/xxxxxxxxxxxx/SIP/Info/critical/1/sipSPIUpdateDynamicPT: Requested payload-Type (114) is reserved by another application
Sep 10 23:30:14.543: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 98
Sep 10 23:30:14.543: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 114
Sep 10 23:30:14.543: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIAllocateFreeDynamicPT: Allocating free Dynamic Payload : 98 for Codec: aacld
Sep 10 23:30:14.543: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(104) reserved for codec No Codec
Sep 10 23:30:14.543: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 96
Sep 10 23:30:14.543: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(104) reserved for codec No Codec
Sep 10 23:30:14.543: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 99
Sep 10 23:30:14.543: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(105) reserved for codec No Codec
Sep 10 23:30:14.543: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 96
Sep 10 23:30:14.543: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(105) reserved for codec No Codec
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 99
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(111) reserved for codec No Codec
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 96
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/critical/1/sipSPICheckDynPayloadUse: Dynamic payload(111) could not be reserved as its in use by other codec mp4a-latm
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/critical/1/sipSPIUpdateDynamicPT: Requested payload-Type (111) is reserved by another application
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 99
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 111
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIAllocateFreeDynamicPT: Allocating free Dynamic Payload : 99 for Codec:mp4a-latm
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec No Codec
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 96
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/critical/1/sipSPICheckDynPayloadUse: Dynamic payload(101) could not be reserved as its in use by other codec
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/critical/1/sipSPIUpdateDynamicPT: Requested payload-Type (101) is reserved by another application
Sep 10 23:30:14.544: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 102
Sep 10 23:30:14.545: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 101
Sep 10 23:30:14.545: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIAllocateFreeDynamicPT: Allocating free Dynamic Payload : 101 for Codec:
Sep 10 23:30:14.545: //584/699165018315/SIP/Info/notify/1/sipSPIDoAudioNegotiation: Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1
Sep 10 23:30:14.545: //584/699165018315/SIP/Info/notify/1/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled
Sep 10 23:30:14.545: //584/699165018315/SIP/Info/info/8/sipSPISetFaxFlags: FAX_PASSTHROUGH = 0, END_FAX_PASSTHROUGH = 0
Sep 10 23:30:14.545: //584/699165018315/SIP/Info/info/1/sipSPIDoDTMFRelayNegotiation: m-line index 1
Sep 10 23:30:14.545: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec
Sep 10 23:30:14.545: //584/699165018315/SIP/Info/notify/1/sipSPIReserveRtpNtePayload: Reserved the payload type 101 for RTP-NTE
Sep 10 23:30:14.545: //584/699165018315/SIP/Info/info/32/sipSPIDoDTMFRelayNegotiation: RTP-NTE DTMF relay option
Sep 10 23:30:14.545: //584/699165018315/SIP/Info/info/1/sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events.
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 0
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 114
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: encname is opus
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 9
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 104
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: encname is G7221
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 105
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: encname is G7221
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 8
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 18
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 111
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: encname is x-ulpfecuc
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 101
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: encname is telephone-event
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8209/sip_sdp_get_modem_relay_cap_params: mer_dtmf_found 0
Sep 10 23:30:14.546: //-1/xxxxxxxxxxxx/SIP/Info/info/8209/sip_sdp_get_modem_relay_cap_params: V150 NSE payload = 0, SSE payload = 0, SPRT payload=0
Sep 10 23:30:14.546: //584/699165018315/SIP/Info/critical/8193/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
Sep 10 23:30:14.546: //584/699165018315/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 10 23:30:14.546: //584/699165018315/SIP/Info/notify/1/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=172.20.11.11, dest_port=17966
Sep 10 23:30:14.547: //584/699165018315/SIP/Info/verbose/1/sipSPIValidateConnectionAddress: Dest port = 54892
SIP: (584) Attribute mid, level 2 instance 1 not found.
Sep 10 23:30:14.547: //584/699165018315/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:2, Mode : 1
Sep 10 23:30:14.547: //584/699165018315/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr
Sep 10 23:30:14.547: //584/699165018315/SIP/Info/verbose/1/sipSPI_detect_media_loop: Number of media loops detected - 0
Sep 10 23:30:14.547: //-1/xxxxxxxxxxxx/SIP/Info/sipSPI_Check_If_ICE_Needed: CANDIDATE attribute, level 1not found.
Sep 10 23:30:14.547: //-1/xxxxxxxxxxxx/SIP/Info/sipSPI_Check_If_ICE_Needed: CANDIDATE attribute, level 2not found.
Sep 10 23:30:14.547: //584/699165018315/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Sep 10 23:30:14.547: //584/699165018315/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr
Sep 10 23:30:14.547: //584/699165018315/SIP/Info/info/4096/ccsip_offer_ans_handle_rcvd_sdp:
Sep 10 23:30:14.547: //584/699165018315/SIP/Info/info/4096/ccsip_offer_ans_is_invite_offer_valid: TRUE
Sep 10 23:30:14.548: //584/699165018315/SIP/Info/info/4096/ccsip_offer_ans_common_offer_rcvd_hdlr:
Sep 10 23:30:14.548: //584/699165018315/SIP/Info/info/4096/ccsip_iwf_handle_network_event:
Sep 10 23:30:14.548: //584/699165018315/SIP/Info/info/4096/sip_iwf_def_ed_rcvd_sdp_hdlr:
Sep 10 23:30:14.548: //584/699165018315/SIP/Info/info/1/sipSPIAssignStreamCallId: Assigning streamid 584 to stream 1. Old streamid = -1
Sep 10 23:30:14.548: //584/699165018315/SIP/Info/info/1/sipSPI_initialize_audio_channelInfo_from_sdp: stream_callid = 584, peer_stream_callid = -1
Sep 10 23:30:14.548: //584/699165018315/SIP/Info/info/8/sipSPI_ipip_init_stream_ex_caps_params: T38 version 0
Sep 10 23:30:14.548: //584/699165018315/SIP/Info/info/8/sipSPI_ipip_init_stream_ex_caps_params: CCB fax rate 2 ipip_caps rate 14400
SIP: (584) setup attribute, level 1 instance 1 not found.
SIP: (584) connection attribute, level 1 instance 1 not found.
SIP: (584) Attribute label, level 1 instance 1 not found.
SIP: (584) a=framerate attribute, level 1 instance 1 not found.
Sep 10 23:30:14.548: //584/699165018315/SIP/Info/verbose/1025/sipSPI_initialize_audio_channelInfo_from_sdp: Populating Address in channelInfo
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/info/1/sipSPI_initialize_audio_channelInfo_from_sdp: Media dest addr for stream 1='172.20.11.11', port=17966
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/info/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Processing Media line = 1;Codec = g711ulaw; PType = 0
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/verbose/1/codec_found: Codec to be matched: g711ulaw(5)
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/verbose/1/codec_found: Match Found at index 0.
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/verbose/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Add Channel for Codec g711ulaw
Sep 10 23:30:14.549: //584/699165018315/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 10 23:30:14.549: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, uptime: 20
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/info/1/sipSPI_ipip_update_codec_params_in_stream_channelInfo: Adding negotiated codec g711ulaw ptype 0 time 20, bytes 160 as channel 0 mline 1 ss 1
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/info/131073/sipSPIBwCacCalcAudioBandwidth: calculated audio bandwidth : codec g711ulaw bw 64000
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/info/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Processing Media line = 1; Codec = No Codec ; PType = 114
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/info/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Processing Media line = 1; Codec = g722-64; PType = 9
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/verbose/1/codec_found: Codec to be matched: g722-64(37)
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/notify/1/codec_found: Match Not Found for g722-64.
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/info/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Processing Media line = 1; Codec = No Codec ; PType = 104
Sep 10 23:30:14.549: //584/699165018315/SIP/Info/info/1/sip_iwf_sip_copy_audio_sdp_to_local_channelInfo: Processing Media line = 1; Codec = No Codec ; PType = 105o=CiscoSystemsSIP-GW-UserAgent 1529 1292 IN IP4 172.20.13.1
s=SIP Call
c=IN IP4 172.20.13.1
t=0 0
m=audio 8298 RTP/AVP 0 19
c=IN IP4 172.20.13.1
a=rtpmap:0 PCMU/8000
a=rtpmap:19 CN/8000
a=ptime:20
m=application 8296 UDP/UDT/IX *
c=IN IP4 172.20.13.1
a=setup:actpass
a=ixmap:11

Sep 10 23:30:14.602: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F84899AAF60
Sep 10 23:30:14.613: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F84899AAF60 with refCount = 1
Sep 10 23:30:14.613: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F8488D44A88 with refCount = 1
Sep 10 23:30:14.614: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [172.20.13.15]:49263, local_address:[172.20.13.1]
Sep 10 23:30:14.614: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F84899AAF60
Sep 10 23:30:14.614: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Sep 10 23:30:14.614: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Sep 10 23:30:14.614: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7F8488D44A88) counter, current msg->refCount = 2
Sep 10 23:30:14.615: //586/699165018315/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 172.20.13.1:5060;br
CME-Pod1#anch=z9hG4bKA648A
From: "WINChad" <sip:2005@172.20.13.1>;tag=20CB2FA-B8D
To: <sip:2004@172.20.13.15>;tag=002155538a0b0016801f5a10-264baf8c
Call-ID: 699B4F6F-F2F411EA-831BC1BF-4529CFD2@172.20.13.1
Date: Thu, 10 Sep 2020 23:30:11 GMT
CSeq: 101 INVITE
Warning: 399 Bad Request - 'Invalid SDP informat
CME-Pod1#ion'
Server: Cisco-CP7975G/9.4.2
Contact: <sip:D56CF0-64@172.20.13.15:5060;transport=udp>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE,INFO
Remote-Party-ID: "Will" <sip:2004@172.20.13.1>;party=called;id-type=subscriber;privacy=off;screen=yes
Allow-Events: kpml,dialog
Content-Length: 0


Sep 10 23:30:14.615: //586/699165018315/SIP/Info/verbose/1024/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
Sep 10 23:30:14.615: //586/699165018315/SIP/Info/verbose/32768/ccsip_ipip_media_forking_copy_prev_best_rpid: MF: This is best rpid..
Sep 10 23:30:14.615: //586/699165018315/SIP/Info/verbose/4096/sipSPISetInfoFromRpid: calling host comes from rpid 172.20.13.1
Sep 10 23:30:14.615: //586/699165018315/SIP/Info/info/4096/sipSPISetInfoFromRpid: Received current remote name: Will, current remote number: 2004
Sep 10 23:30:14.615: //586/699165018315/SIP/Info/info/4096/sipSPISetInfoFromRpid: Received ;screen=yes ;privacy=off -> Setting Octet3A 0x81, extended_privacy 0x00
Sep 10 23:30:14.615: //586/699165018315/SIP/Info/info/8192/sipSPIProcessSessionID: SessionID header not present - create uuid
Sep 10 23:30:14.615: //586/699165018315/SIP/Info/info/8192/sipSPIGetTDSessionID: Get peer leg session-ID local uuid - 000076b100105000a000000fc910f652 remote uuid - 00000000000000000000000000000000
Sep 10 23:30:14.616: //586/699165018315/SIP/Info/notify/8192/sipSPI_Add_SessionIDtoTDContainer: localUUID - 00000000000000000000000000000000remoteUUID - 000076b100105000a000000fc910f652
Sep 10 23:30:14.616: //586/699165018315/SIP/Info/info/8192/sipSPI_Add_SessionIDtoTDContainer: Add sessionid to TD container
Sep 10 23:30:14.616: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F84899AAF60 with refCount = 1
Sep 10 23:30:14.616: //586/699165018315/SIP/Info/verbose/4096/sipSPIChangeDeferAckState: Change state from 0 to new state 0, called from :sipSPIPresendProcessing
Sep 10 23:30:14.616: //586/699165018315/SIP/Info/notify/8192/sipSPIAddSessionID: localUUID - 00000000000000000000000000000000 remoteUUID - 000076b100105000a000000fc910f652
Sep 10 23:30:14.616: //586/699165018315/SIP/Info/notify/8192/sipSPIAddSessionID: Session-ID header 000076b100105000a000000fc910f652; remote=00000000000000000000000000000000
Sep 10 23:30:14.616: //586/699165018315/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 1 event
Sep 10 23:30:14.616: //586/699165018315/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Sep 10 23:30:14.641: //584/699165018315/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x7F8480AECDD0
Sep 10 23:30:14.641: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIGetContextFromTable: NO context for key[523]
Sep 10 23:30:14.642: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F848A3BFCA0
Sep 10 23:30:18.873: //-1/xxxxxxxxxxxx/SIP/Info/info/512/udpsock_close_connect: Socket fd: 0 closed for connid 3 with remote port: 5060
Sep 10 23:30:24.739: //583/5C940B8E8314/SIP/Info/verbose/4096/sipSPIUdeleteccCallIdFromTable: Removing call id 247
Sep 10 23:30:24.739: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIDeleteContextFromTable: Context for key=[522] removed.
Sep 10 23:30:24.739: //583/5C940B8E8314/SIP/Info/verbose/4096/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x7F8480AD6AC8 key=00215553-8a0b0004-b4ed98d1-fb7ce488@172.20.13.152004002155538a0b0015eb338b9a-209f6be8
Sep 10 23:30:24.739: //583/5C940B8E8314/SIP/Info/verbose/4096/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Sep 10 23:30:24.740: //583/5C940B8E8314/SIP/Error/sipSPIFlushDeferredQueue: Invalid deferredQueue
Sep 10 23:30:24.740: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F8489FAAA10
Sep 10 23:30:24.740: //583/5C940B8E8314/SIP/Info/verbose/1/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Sep 10 23:30:24.740: //583/5C940B8E8314/SIP/Info/info/8192/sipSPIStopOverlapInfoTimer: Stopping Overlap Info Timer
Sep 10 23:30:24.740: //583/5C940B8E8314/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x7F8480AD6AC8
Sep 10 23:30:26.555: //-1/xxxxxxxxxxxx/SIP/Info/info/512/udpsock_close_connect: Socket fd: 3 closed for connid 5 with remote port: 5060
Sep 10 23:30:35.141: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F848960E370 with refCount = 1
Sep 10 23:30:35.141: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F8488D44A88 with refCount = 1
Sep 10 23:30:35.141: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [172.20.11.11]:5060, local_address:[172.20.13.1]
Sep 10 23:30:35.142: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F848960E370
Sep 10 23:30:35.142: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Sep 10 23:30:35.142: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
IN
CME-Pod1#VITE sip:2004@172.20.13.1;user=phone SIP/2.0
Via: SIP/2.0/UDP 172.20.11.11:5060;branch=z9hG4bK000014f4
From: "WINChad" <sip:2005@172.20.13.1>;tag=000fc910f652006c00006f39-00006e95
To: <sip:2004@172.20.13.1>
Call-ID: 000fc910-f6520034-000017a7-00007c0b@172.20.11.11
Max-Forwards: 70
Session-ID: 0000066c00105000a000000fc910f652;remote=00000000000000000000000000000000
Date: Thu, 10 Sep 2020 22:31:02 GMT
CSeq: 101 INVITE
User-Agent: Cisco-CSF
Contact: <sip:15C51AD-1453@172.20.11.11:5060;transport=udp>;+u.sip!devicename.ccm.cisco.com="J4W"
Expires: 180
Accept: application/sdp
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE,INFO
Remote-Party-ID: "WINChad" <sip:2005@172.20.13.1>;party=calling;id-type=subscriber;privacy=off;screen=yes
Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-ref
CME-Pod1#er,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-c
isco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1
Allow-Events: kpml,dialog
Recv-Info: conference
Recv-Info: x-cisco-conference
Content-Length: 869
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 10952 0 IN IP4 172.20.11.11
s=SIP Call
b=AS:4000
t=0 0
a=cisco-mari:v1
a=cisco-mari-rate
m=audio 24102 RTP/AVP 0 114 9 104 105 8 18 111 101
c=IN IP4 172.20.11.11
a=rtpmap:0 PCMU/8000
a=rtpmap:114 opus/48000/2
a=rtpmap:9 G722/8000
a=rtpmap:104 G7221/16000
a=fmtp:104 bitrate=32000
a=rtpmap:105 G7221/16000
a=fmtp:105 bitrate=24000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:111 x-ulpfecuc/8000
a=extmap:1
CME-Pod1#4/sendrecv http://protocols.cisco.com/timestamp#100us
a=fmtp:111 max_esel=1420;m=8;max_n=32;FEC_ORDER=FEC_SRTP
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=sendrecv
m=application 54358 UDP/UDT/IX *
c=IN IP4 172.20.11.11
a=ixmap:11 xccp
a=setup:actpass
a=fingerprint:sha-256 DD:58:A8:CB:2F:D8:A8:C6:BE:B0:32:28:3B:44:30:2A:A9:FC:EA:F4:23:CB:44:73:61:9D:81:8E:20:BD:AE:CB
a=sendrecv

Sep 10 23:30:35.142: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Sep 10 23:30:35.143: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIIpTrustSilentDiscard: sipSPIIpTrustSilentDiscard: called for method [102]
Sep 10 23:30:35.143: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x7F8480AD6AC8) with key=[525] to table
Sep 10 23:30:35.143: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Sep 10 23:30:35.143: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Sep 10 23:30:35.143: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Sep 10 23:30:35.143: //-1/000000000000/SIP/Info/verbose/4097/ccsip_platform_init_ccb: Initialising rtp session queue
Sep 10 23:30:35.143: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Sep 10 23:30:35.143: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:35.144: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:35.144: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 172.20.13.1 for SIP
Sep 10 23:30:35.144: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 172.20.13.1 for SIP
Sep 10 23:30:35.144: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 172.20.13.1
Sep 10 23:30:35.144: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 0
Sep 10 23:30:35.144: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 172.20.13.1
Sep 10 23:30:35.144: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone EDT to SIP default timezone =GMT
Sep 10 23:30:35.145: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Sep 10 23:30:35.145: //-1/75DAD819831C/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:35.145: //-1/75DAD819831C/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:35.145: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 172.20.13.1 for SIP
Sep 10 23:30:35.145: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 172.20.13.1 for SIP
Sep 10 23:30:35.145: //-1/75DAD819831C/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 172.20.13.1
Sep 10 23:30:35.145: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 0
Sep 10 23:30:35.145: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 172.20.13.1
Sep 10 23:30:35.145: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7F8488D44A88) counter, current msg->ref Count = 2
Sep 10 23:30:35.145: //-1/xxxxxxxxxxxx/SIP/Error/httpish_msg_free: Freeing NULL pointer!
Sep 10 23:30:35.146: //-1/75DAD819831C/SIP/Info/verbose/4096/sipSPIAssignCcbLastRequest: Current ccb(0x7F8480AD6AC8)->last_request = 0x7F8488D44A88, refCount = 2
Sep 10 23:30:35.146: //-1/75DAD819831C/SIP/Info/info/8192/sipSPIExtractSessionID: Parsed localuuid - 0000066c00105000a000000fc910f652 remoteuuid - 00000000000000000000000000000000
Sep 10 23:30:35.146: //-1/75DAD819831C/SIP/Info/notify/8192/sipSPI_Add_SessionIDtoTDContainer: localUUID - 0000066c00105000a000000fc910f652 remoteUUID - 00000000000000000000000000000000
Sep 10 23:30:35.146: //-1/75DAD819831C/SIP/Info/info/8192/sipSPIProcessSessionID: SessionID header is present- 0000066c00105000a000000fc910f652
Sep 10 23:30:35.146: //-1/xxxxxxxxxxxx/SIP/Info/info/2048/sipSPICheckIpip: VOIP dialpeer (peer=0x55F396BE3464) found for sip_user: 2004
Sep 10 23:30:35.146: //-1/75DAD819831C/SIP/Info/info/2048/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
Sep 10 23:30:35.146: //-1/75DAD819831C/SIP/Info/info/4096/sipSPISetCallingInfo: calling host comes from From: 172.20.13.1
Sep 10 23:30:35.147: //-1/75DAD819831C/SIP/Info/verbose/32768/ccsip_ipip_media_forking_copy_prev_best_rpid: MF: This is best rpid..
Sep 10 23:30:35.147: //-1/75DAD819831C/SIP/Info/verbose/4096/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with RPID header data
Sep 10 23:30:35.147: //-1/75DAD819831C/SIP/Info/verbose/4096/sipSPISetInfoFromRpid: calling host comes from rpid 172.20.13.1
Sep 10 23:30:35.147: //-1/75DAD819831C/SIP/Info/info/4096/sipSPISetInfoFromRpid: Received current remote name: WINChad, current remote number: 2005
Sep 10 23:30:35.147: //-1/75DAD819831C/SIP/Info/info/4096/sipSPISetInfoFromRpid: Received ;screen=yes ;privacy=off -> Setting Octet3A 0x81, extended_privacy 0x00
Sep 10 23:30:35.147: //-1/75DAD819831C/SIP/Info/info/2048/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
Sep 10 23:30:35.147: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentGTD: No GTD found in inbound container
Sep 10 23:30:35.147: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentCSTA: No CSTA found in inbound container
Sep 10 23:30:35.148: //-1/75DAD819831C/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x7F8480AD6AC8 key=000fc910-f6520034-000017a7-00007c0b@172.20.11.112004 balance 1
Sep 10 23:30:35.148: //-1/75DAD819831C/SIP/Info/info/8192/sipSPIMatchSrcIpGroup: Match not found on carrier id
Sep 10 23:30:35.148: //-1/75DAD819831C/SIP/Info/critical/8192/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 2004
Sep 10 23:30:35.148: //-1/75DAD819831C/SIP/Info/critical/8192/sipSPIMatchSrcIpGroup: Match not found on destination pattern: 2005
Sep 10 23:30:35.148: //-1/75DAD819831C/SIP/Info/info/12288/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name WINChad, number 2005, Calling oct3 0x00, oct_3a 0x81, Called number 2004
Sep 10 23:30:35.148: //-1/75DAD819831C/SIP/Info/verbose/1024/sipSPIGetViaHostInURLFormat: VIA URL:sip:172.20.11.11:5060, Host:172.20.11.11
Sep 10 23:30:35.149: //-1/75DAD819831C/SIP/Info/verbose/67584/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : 2005
Sep 10 23:30:35.149: //-1/75DAD819831C/SIP/Info/critical/1024/sipSPIGetFromCalledPartyId: P-Called-Party-ID header not found
Sep 10 23:30:35.149: //-1/75DAD819831C/SIP/Info/critical/1024/sipSPIGetPeerByCalledPartyId: P-Called-Party-ID not found or parse error
Sep 10 23:30:35.149: //-1/75DAD819831C/SIP/Info/critical/10240/sipSPIGetCallConfig: No match found for P-Called-Party-ID
Sep 10 23:30:35.149: //-1/75DAD819831C/SIP/Info/info/65536/ccsip_sipline_set_vrf: Phone number 2005 from ccb calling number
Sep 10 23:30:35.149: //-1/xxxxxxxxxxxx/SIP/Info/info/65536/ccsip_sipline_set_vrf: Obtained phone tag (5) from Dial-peer
Sep 10 23:30:35.149: //-1/xxxxxxxxxxxx/SIP/Info/info/65536/ccsip_sipline_set_vrf: Got vrf 0 for phone tag 5
Sep 10 23:30:35.149: //-1/75DAD819831C/SIP/Info/info/8192/sipSPIGetCallConfig: VRF id = 0
Sep 10 23:30:35.149: //-1/75DAD819831C/SIP/Info/info/2048/sipSPIGetCallConfig: Peer tag 40004 matched for incoming call
Sep 10 23:30:35.149: //-1/75DAD819831C/SIP/Info/verbose/1024/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
Sep 10 23:30:35.150: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Sep 10 23:30:35.150: //-1/75DAD819831C/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:35.150: //-1/75DAD819831C/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Sep 10 23:30:35.150: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 172.20.13.1 for SIP
Sep 10 23:30:35.150: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 172.20.13.1 for SIP
Sep 10 23:30:35.150: //-1/75DAD819831C/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 172.20.13.1
Sep 10 23:30:35.150: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 0
Sep 10 23:30:35.150: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 172.20.13.1
Sep 10 23:30:35.150: //-1/75DAD819831C/SIP/Info/info/2048/sipSPISetAudioForcedConfig: audio forced config is set to FALSE
Sep 10 23:30:35.150: //-1/75DAD819831C/SIP/Info/verbose/1024/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
Sep 10 23:30:35.150: //-1/75DAD819831C/SIP/Info/verbose/2048/sipSPIGetCallConfig: Media Antitrombone disabled
Sep 10 23:30:35.150: //-1/75DAD819831C/SIP/Info/notify/65536/sipSPISetMediaFlowMode: Forcing the flow mode to FLOW-THROUGH for SIP line
Sep 10 23:30:35.150: //-1/75DAD819831C/SIP/Info/info/8192/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/info/2304/sipSPISetMediaFlowMode: xcoder high-density disabled
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/info/8192/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/verbose/4/update_video_params_from_config: Checking Video Type Rate=-1 video_codec_allowed=1F
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/verbose/12288/sipSPIGetModemInfoPerCall: peer_callID=0
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/critical/32768/ccsip_ipip_media_forking_update_preferred_codec: MF: Not a Forked SIP leg..
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/info/8704/sipSPIGetCallConfig: Incoming: No defer BYE for last call stats
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/info/34816/ccsip_ipip_media_forking_anchor_leg_config: MF: en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/info/34816/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer has no media class recorder.
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/info/32768/ccsip_ipip_media_forking_intra_frame_request_config: MF: FIR en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/info/32768/ccsip_ipip_media_forking_get_forked_leg_config: MF: This leg is not forked call leg.
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/verbose/1/ccsip_set_srtp_config: No Srtp configure for this leg.
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/info/8192/sipSPIGetCallConfig: Media forking disabled
Sep 10 23:30:35.151: //-1/75DAD819831C/SIP/Info/critical/11264/ccsipInitDSCPPolicyInfo: No DSCP Profile configured, No RPH 2 DSCP Mapping and DSCP policing
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/verbose/8192/sipSPIGetCallConfig: Initilise the DSCP policy
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/verbose/8192/sipSPICheckFAAnatAssymetricOrDO2EO: Not a SIP-SIP call or not in FA mode
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/notify/8192/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Line
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/verbose/2048/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/notify/10240/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/info/4096/sipSPIContinueNewMsgInvite: Calling name WINChad, number 2005, Calling oct3 0x00, oct_3a 0x81, ext_priv 0x00, Called number 2004, oct3 0x00
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/info/4096/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/critical/10240/sipSPIValidateRequestUri: Not Enabled
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/info/65536/UpdateSIPDirectVoiceMailNumber: Direct call from SIP line: called number 2004
Sep 10 23:30:35.152: //-1/75DAD819831C/SIP/Info/info/131072/sipSPIRscmsmAvail: Value returned by check is = 0
Sep 10 23:30:35.153: //588/75DAD819831C/SIP/Info/critical/4096/sipSPICheckOutBoundDpProvisioning: Cannot obtain the the header bitmap
Sep 10 23:30:35.153: //588/75DAD819831C/SIP/Info/critical/8192/sipSPI_ipip_IsSDPPassthruEnabled: - 0
Sep 10 23:30:35.153: //588/75DAD819831C/SIP/Info/info/8192/sipSPIExtractSessionID: Parsed localuuid - 0000066c00105000a000000fc910f652 remoteuuid - 00000000000000000000000000000000
Sep 10 23:30:35.153: //588/75DAD819831C/SIP/Info/notify/8192/sipSPI_Add_SessionIDtoTDContainer: localUUID - 0000066c00105000a000000fc910f652remoteUUID - 00000000000000000000000000000000
Sep 10 23:30:35.153: //588/75DAD819831C/SIP/Info/info/8192/sipSPIProcessSessionID: SessionID header is present- 0000066c00105000a000000fc910f652
Sep 10 23:30:35.153: //588/75DAD819831C/SIP/Info/notify/8192/Session-Timer/sipSTSLExtractSessionExpiresHdr: Not received session expires header
Sep 10 23:30:35.153: //588/75DAD819831C/SIP/Info/info/8192/Session-Timer/sipSTSLGetInternalSREvent: E_STSL_INITIAL_SR_REQ
Sep 10 23:30:35.153: //588/75DAD819831C/SIP/Info/info/8192/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
Sep 10 23:30:35.153: //588/75DAD819831C/SIP/Info/critical/1024/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container
Sep 10 23:30:35.154: //588/75DAD819831C/SIP/Info/critical/1024/sipSPIProcessDiversionHeader: No diversion headers recvd from app container
Sep 10 23:30:35.154: //588/75DAD819831C/SIP/Info/critical/16384/sipSPIProcessReplacesHeader: No replaces hdr found
Sep 10 23:30:35.245: //588/75DAD819831C/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x7F8480AD6AC8
Sep 10 23:30:35.245: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIGetContextFromTable: NO context for key[525]
Sep 10 23:30:35.245: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F847FA324B8
Sep 10 23:30:35.654: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F8488D44A88 with refCount = 1
Sep 10 23:30:35.654: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F8479894E38 with refCount = 1
Sep 10 23:30:35.655: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [172.20.11.11]:5060, local_address:[172.20.13.1]
Sep 10 23:30:35.655: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F8488D44A88
Sep 10 23:30:35.655: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Sep 10 23:30:35.655: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received:
Sep 10 23:30:35.772: //592/762A2F1E8323/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x7F8480AD6AC8
Sep 10 23:30:35.773: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIGetContextFromTable: NO context for key[527]
Sep 10 23:30:35.773: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F8480645828
Sep 10 23:30:37.281: //596/7712E2BF832A/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x7F8480AD6AC8
Sep 10 23:30:37.281: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIGetContextFromTable: NO context for key[529]
Sep 10 23:30:37.281: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F848A377BC8
CME-Pod1#

Labels:
0 Helpful
0 Replies 0
Customers Also Viewed These Support Documents