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
1911
Views
0
Helpful
2
Replies

SIP SRST Resgistration Issue

Vishal Parekh
Level 1
Level 1

‎11-20-2017 11:25 PM - edited ‎03-19-2019 12:57 PM

Hello All,

 

I have below configuration for my SIP phone Registration on my H323 voice gateway.

I have cisco 8841 SIP phones & which are not falling back into SRST.

Config detail;

voice register global
 mode esrst
 source-address 130.9.200.114 port 5060
 no allow-hash-in-dn
 timeouts interdigit 5
 system message SRST MODE
 max-dn 400
 max-pool 200
!
voice register pool  1
 id network 130.9.200.114 mask 255.255.255.252
 dtmf-relay rtp-nte sip-notify
 codec g711ulaw
 no vad
!

 

I am manually disconnecting site phones to communicate with CUCM & allowing them to register locally on on Voice gateway. I am getting below error when I have run Debug CCSIP Messages on my VG.

 

000562: *Nov 21 11:17:44.959 UAE: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
REGISTER sip:130.9.200.114 SIP/2.0
Via: SIP/2.0/UDP 130.9.112.68:5060;branch=z9hG4bK0aa8a255
From: <sip:2801@130.9.200.114>;tag=00425ac7be1200984cb9731d-40d8dc38
To: <sip:2801@130.9.200.114>
Call-ID: 00425ac7-be120184-0df98245-1ee858d1@130.9.112.68
Max-Forwards: 70
Session-ID: 66340a6d00105000a00000425ac7be12;remote=00000000000000000000000000000000
Date: Tue, 21 Nov 2017 07:08:32 GMT
CSeq: 165 REGISTER
User-Agent: Cisco-CP8841/11.5.1
Contact: <sip:db05d1a3-9baa-3b32-a452-203403df57f3@130.9.112.68:wr 5060;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-0000-0000-00425ac7be12>";+u.sip!devicename.ccm.cisco.com="SEP00425AC7BE12";+u.sip!model.ccm.cisco.com="683"
Supported: extended-refer,norefersub,X-cisco-srtp-fallback,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1
Content-Length: 0
Reason: SIP;cause=200;text="cisco-alarm:10 Name=SEP00425AC7BE12 ActiveLoad=sip88xx.11-5-1-18.loads InactiveLoad=sip88xx.11-0-1SR1-2.loads Last=tcp_timeout"
Expires: 3600


000563: *Nov 21 11:17:45.459 UAE: //13/E78393F0800E/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 130.9.112.68:5060;branch=z9hG4bK0aa8a255
From: <sip:2801@130.9.200.114>;tag=00425ac7be1200984cb9731d-40d8dc38
To: <sip:2801@130.9.200.114>;tag=18434AB0-579
Date: Tue, 21 Nov 2017 11:17:44 GMT
Call-ID: 00425ac7-be120184-0df98245-1ee858d1@130.9.112.68
Server: Cisco-SIPGateway/IOS-15.5.3.M4a
CSeq: 165 REGISTER
Content-Length: 0

Call Manager configuration is fine.

Kindly let me know if I am missing something.

 

0 Helpful
2 Replies 2

Vishal Parekh
Level 1
Level 1

‎11-21-2017 03:41 AM

Hello All,

 

Below are some more errors from SRST - Gateway.

 

SGHSHJ-MDF-VG01#
SGHSHJ-MDF-VG01#
SGHSHJ-MDF-VG01#
SGHSHJ-MDF-VG01#
SGHSHJ-MDF-VG01#
SGHSHJ-MDF-VG01#
000235: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created

msg=0x2C26D18 with refCount = 1
000236: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created

msg=0x2C52B98 with refCount = 1
000237: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads:

Msg enqueued for SPI with IP addr: [130.9.112.75]:5060,

local_address:[130.9.200.114]
000238: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed

msg=0x2C26D18
000239: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_eve

nt: ccsip_spi_get_msg_type returned: 2 for event 1
000240: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:

context=0x0
000241: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
REGISTER sip:130.9.200.114 SIP/2.0
Via: SIP/2.0/UDP 130.9.112.75:5060;branch=z9hG4bK0a4b1884
From: <sip:2802@130.9.200.114>;tag=f8a5c5ea794500533d2cbee0-

5a38c860
To: <sip:2802@130.9.200.114>
Call-ID: f8a5c5ea-79450263-77761f98-5cf9a365@130.9.112.75
Max-Forwards: 70
Session-ID:

7fe576f100105000a000f8a5c5ea7945;remote=000000000000000000000000

00000000
Date: Tue, 21 Nov 2017 11:26:52 GMT
CSeq: 144 REGISTER
User-Agent: Cisco-CP8865/11.5.1
Contact: <sip:20e9e4c6-3dca-cee0-0f59-

bbbe42c7c108@130.9.112.75:5060;transport=udp>;

+sip.instance="<urn:uuid:00000000-0000-0000-0000-

f8a5c5ea7945>";+u.sip!

devicename.ccm.cisco.com="SEPF8A5C5EA7945";+u.sip!

model.ccm.cisco.com="36225";video
Supported: extended-refer,norefersub,X-cisco-srtp-fallback,X-

cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1
Content-Length: 0
Reason: SIP;cause=200;text="cisco-alarm:10 Name=SEPF8A5C5EA7945

ActiveLoad=sip8845_65.11-5-1-18.loads

InactiveLoad=sip8845_65.11-0-1SR1-2.loads Last=tcp_timeout"
Expires: 3600


000242: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor:

Checking Invite Dialog
000243: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIFindRegisterCcb:

*****CCB NOT found in UAS Request table. ccb=0x0
000244: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable:

Added context(0x42F2E478) with key=[6] to table
000245: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Function/sipSPIInitContainerBlock:
000246: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Function/sipSPI_ipip_init_dialpeer_video_caps

:
000247: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Function/sipSPI_ipip_init_codec_profile:
000248: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
000249: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init

:
000250: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_i

nit: MF: Queue is initialised..
000251: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Function/ccsip_platform_init_ccb:exit@11152:
000252: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Function/sipSPI_sip2h323_copy_replaces:
000253: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Function/sipSPIUpdateResponseInfo:
000254: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog

Transaction Address 130.9.112.75,Port 5060, Transport 1, SentBy

Port 5060vrfid 0
000255: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Function/resolve_sig_ip_address_to_bind:
000256: *Nov 21 15:36:09.919 UAE: //-

1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind

: VRF id = 0
000257: *Nov 21 15:36:09.919 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress:

ip_address IPv4 130.9.200.114 for SIP
000258: *Nov 21 15:36:09.923 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind:

ip_get_ifaddress IPv4 130.9.200.114 for SIP
000259: *Nov 21 15:36:09.923 UAE: //-

1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind

: signaling bind address : 130.9.200.114
000260: *Nov 21 15:36:09.923 UAE: //-

1/000000000000/SIP/Function/sipSPIGetLocalHostName:
000261: *Nov 21 15:36:09.923 UAE: //-

1/000000000000/SIP/Function/sipSPIGetLocalHostName:
000262: *Nov 21 15:36:09.923 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind

: return addr 130.9.200.114
000263: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/ccsip_is_valid_ccb:
000264: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/ccsip_is_valid_ccb:exit@8232:
000265: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/ccsipDisplayMsg:
000266: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/ccsipDisplayMsg:exit@13221:
000267: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/State/sipSPIChangeState: 0x42F2E478 : State

change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE,

SUBSTATE_NONE)
000268: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/act_idle_new_message:
000269: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIUpdateResponseInfo:
000270: *Nov 21 15:36:09.923 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq

Transaction Address 130.9.112.75,Port 5060, Transport 1, SentBy

Port 5060vrfid 0
000271: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPICheckRequestExt:
000272: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIAddDateHeaderString:
000273: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIGetCallHeaderString:
000274: *Nov 21 15:36:09.923 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader:

Converting TimeZone UAE to SIP default timezone = GMT
000275: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIUpdateResponseInfo:
000276: *Nov 21 15:36:09.923 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq

Transaction Address 130.9.112.75,Port 5060, Transport 1, SentBy

Port 5060vrfid 0
000277: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sip_process_request_cseq:
000278: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIHandleInfoRetransmission:
000279: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPICheckRequestHeaderMaxForwards:
000280: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIVerifyURIScheme:
000281: *Nov 21 15:36:09.923 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment

msg (0x2C52B98) counter, current msg->refCount = 2
000282: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIPushInboundContainerIntoCCB:
000283: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sact_idle_new_message_register:
000284: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/ccsip_api_register_allow_1XXResponse

:
000285: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIStopRemoveTransTimer:
000286: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIStopTimer:
000287: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Info/verbose/4096/sipSPIAssignCcbLastRequest:

Current ccb(0x42F2E478)->last_request = 0x2C52B98, refCount = 2
000288: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIGenerateToTag:
000289: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIAppendTagParam:
000290: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sact_received_register_check_errors:
000291: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIUaddCcbToUASReqTable:
000292: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIUaddCcbToTable:
000293: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added

to table. ccb=0x42F2E478 key=f8a5c5ea-79450263-77761f98-

5cf9a365@130.9.112.752802f8a5c5ea794500533d2cbee0-5a38c860

balance 1
000294: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/sipSPIPopInboundContainerFromCCB:
000295: *Nov 21 15:36:09.923 UAE: //-

1/01314DE18007/SIP/Function/ccsip_api_register_ind:
000296: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Event/sact_idle_new_message_register:
 ccsip_api_register_ind return value : SIP_SUCCESS

000297: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/verbose/4096/sipSPIUaddccCallIdToTable

: Adding call id 9 to table
000298: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/State/sipSPIChangeState: 0x42F2E478 : State

change from (STATE_IDLE, SUBSTATE_NONE)  to

(SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE)
000299: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/ccsipValidateAndPushInboundContain

erInCcb:
000300: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/sipGwPushInboundContainerIntoCCB:
000301: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/ccsipValidateAndPushInboundContain

erInCcb:exit@1428:
000302: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/notify/262144/ccsip_register_process_i

ncoming_register: CCSIP_REGISTER:: REGISTER request
000303: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/ccsip_is_valid_ccb:
000304: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/ccsip_is_valid_ccb:exit@8232:
000305: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/ccsip_spi_register_incoming_regist

ration:
000306: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/notify/262144/ccsip_spi_register_incom

ing_registration: registration expires timer max is 3600 and min

is 120

000307: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/ccsip_register_set_expires_time:
000308: *Nov 21 15:36:09.923 UAE: //-

1/xxxxxxxxxxxx/SIP/Error/ccsip_reg_search_call_id_table:
 No entry found in sipCallID Table
000309: *Nov 21 15:36:09.923 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/262144/ccsip_process_registration_p

assthrough: RCB Pointer : [0x0]
000310: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/sipSPIGetRegisterConfig:
000311: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/ccsipInitPldCallInfo:
000312: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/ccsipInitPldCallingInfo:
000313: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/ccsip_apply_sip_to_pstn_calling_po

licy:
000314: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/verbose/1024/sipSPIGetViaHostInURLForm

at: VIA URL:sip:130.9.112.75:5060, Host:130.9.112.75
000315: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/sipSPISetSipProfilesTag:
000316: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/notify/10240/sipSPIGetRegisterConfig:

Using Global Config
000317: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/info/2048/sipSPIGetRegisterConfig:

Peer-Tag = 100, Reg-Pthru = Disable
000318: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/sipSPI_ipip_GetHdrPassthruCfg:
000319: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassth

ruCfg: Hdr passthrough config:1 tag:0
000320: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/verbose/2048/sipSPI_ipip_GetCopyListCf

g: Copy-list config:2 tag:0
000321: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/sipSPIGetExtensionCfg:
000322: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP

extension config:1, check sys cfg:1
000323: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/notify/10240/sipSPI_ipip_build_consoli

dated_header_list: Both passthru and copylist are disabled
000324: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/sipSPI_ipip_build_consolidated_hea

der_list:exit@6824:
000325: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/critical/262144/ccsip_process_registra

tion_passthrough: Register not for passthrough, Try CME
000326: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/info/262144/ccsip_process_registration

_passthrough: Resetting peer_tag
000327: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/info/262144/ccsip_spi_register_incomin

g_registration: Register for CME case
000328: *Nov 21 15:36:09.923 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_spi_register_get_rcb:

Getting New RCB [0x2B30BB8]
000329: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Info/verbose/262144/ccsip_spi_update_cc_cal

lid: ccCallId for RCB is 10
000330: *Nov 21 15:36:09.923 UAE: //-

1/xxxxxxxxxxxx/SIP/Error/sipSPI_validate_own_ip_addr:
 ReqLine IP addr does not match with host IP addr
000331: *Nov 21 15:36:09.923 UAE:

//9/01314DE18007/SIP/Function/ccsip_spi_register_set_incoming_li

ne_features:
000332: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Info/info/65536/ccsip_sipline_set_vrf:

Phone number 2802 from contact passed to this function
000333: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/65536/ccsip_sipline_set_vrf:

Obtained phone tag (0) from Dial-peer
000334: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/65536/ccsip_sipline_set_vrf:

Getting phone index for phone 2802
000335: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/resolve_sig_ip_address_to_bind:
000336: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Info/info/8192/resolve_sig_ip_address_to_bi

nd: VRF id = 0
000337: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress:

ip_address IPv4 130.9.200.114 for SIP
000338: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind:

ip_get_ifaddress IPv4 130.9.200.114 for SIP
000339: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Info/info/8192/resolve_sig_ip_address_to_bi

nd: signaling bind address : 130.9.200.114
000340: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIGetLocalHostName:
000341: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIGetLocalHostName:
000342: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind

: return addr 130.9.200.114
000343: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Error/ccsip_spi_register_incoming_registrat

ion:
 No matching pool for this REGISTER contact - 2802@130.9.112.75
000344: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_spi_registration_failed:
000345: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Info/notify/262144/ccsip_spi_registration_f

ailed:  Sending Register Response 404

000346: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP

SPI : SIPSPI_EV_REGISTER_RESP
000347: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Info/verbose/262144/ccsipGetCCBFromRCB:

Retrived CCB pointer [0x42F2E478]
000348: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/262144/sipSPIFreePthruDataFromRP

CBorRCB: RCB =[0x2B30BB8].. RPCB =[0x0]
000349: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/9216/sipSPIPassThruHdrContainerF

reeHelper: Data -->[0x0]
000350: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Error/sipSPI_ipip_PassthruContentContainerFre

eHelper:
 Null ContentQ - Exit
000351: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Error/ccsip_rpcb_search_cc_call_id_table:
 No entry found in ccCallID Table
000352: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_spi_register_free_rcb

: Freeing rcb [0x2B30BB8]
000353: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_spi_register_incoming_regist

ration:exit@4078:
000354: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_eve

nt: ccsip_spi_get_msg_type returned: 3 for event 41
000355: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIUisValidCcb:
000356: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_is_valid_ccb:
000357: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_is_valid_ccb:exit@8232:
000358: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIUisValidCcb:exit@4099:
000359: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIPreprocessAppEvent:
000360: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipAppMsgPlatformPreprocessor:
000361: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipAppMsgPlatformPreprocessor:exit

@3301:
000362: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIPushNewContainerIntoCCB:
000363: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/act_incoming_register_response:
000364: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIPresendProcessing:
000365: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIProcessPresendRandomContact:
000366: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Info/verbose/8192/sipSPIPresendProcessing:

Presend Processing called for 13 event
000367: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPI_ipip_PassthruCopyListDataPr

ocessing:
000368: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPI_ipip_GetPassthruCopyListDat

aFromTdContainer:
000369: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPI_ipip_GetPassthruCopyListDat

aFromTdContainer:
000370: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created

msg=0x2F78370 with refCount = 1
000371: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPISendResponse:
000372: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIResponseGetAssociatedContain

er:
000373: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIPushSubsqTransMsgContainerIn

toHolder:
000374: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIAddStdHeaders:
000375: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIAppendTagParam:
000376: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIGetCallHeaderString:
000377: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIAddWarningHeader:
000378: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIAddWarningHeader:
000379: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Info/info/4096/sipSPISendResponse:

Associated container=0x4322A428 to REGISTER Response 404
000380: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBod

y: sipSPIAppHandleContainerBody len 0
000381: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Transport/sipSPISendResponse: Sending INFO

Response to the transport layer
000382: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Transport/sipSPITransportSendMessage:

msg=0x2F78370, addr=130.9.112.75, port=5060, sentBy_port=5060,

local_addr=130.9.200.114, is_req=0, transport=1, switch=0,

callBack=0x9D87D58
000383: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_profile_application:
000384: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIGetExtensionCfg:
000385: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP

extension config:1, check sys cfg:1
000386: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_profile_application:exit@233

42:
000387: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_extension_application:
000388: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIGetExtensionCfg:
000389: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP

extension config:1, check sys cfg:1
000390: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_extension_application:exit@3

5158:
000391: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Transport/sipSPITransportSendMessage:

Proceedable for sending msg immediately
000392: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Transport/sipTransportLogicSendMsg: Trying

to send resp=0x2F78370 to default port=5060
000393: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection:

connection required for raddr:130.9.112.75, rport:5060 with

laddr:130.9.200.114

000394: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId:

Registering gcb=0x42F2E478 with connection=0x423AA3BC context

list
000395: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Transport/sipTransportLogicSendMsg:

Connection obtained...sending msg=0x2F78370
000396: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage:

Posting send for msg=0x2F78370, addr=130.9.112.75, port=5060,

local_addr=130.9.200.114, connId=3 vrfid=0 for UDP
000397: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sentRegisterResponse:
000398: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIStartRemoveTransTimer:
000399: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/sipSPISetTimer:
000400: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement

msg (0x2C52B98) counter, current msg->refCount = 1
000401: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/State/sipSPIChangeState: 0x42F2E478 : State

change from (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE)  to

(STATE_IDLE, SUBSTATE_NONE)
000402: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/force_internal_queue_lookup:
000403: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_is_valid_ccb:
000404: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_is_valid_ccb:exit@8232:
000405: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Function/ccsipDisplayMsg:
000406: *Nov 21 15:36:09.927 UAE:

//9/01314DE18007/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 130.9.112.75:5060;branch=z9hG4bK0a4b1884
From: <sip:2802@130.9.200.114>;tag=f8a5c5ea794500533d2cbee0-

5a38c860
To: <sip:2802@130.9.200.114>;tag=2D0018-92E
Date: Tue, 21 Nov 2017 15:36:09 GMT
Call-ID: f8a5c5ea-79450263-77761f98-5cf9a365@130.9.112.75
Server: Cisco-SIPGateway/IOS-15.5.3.M4a
CSeq: 144 REGISTER
Content-Length: 0


000407: *Nov 21 15:36:09.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed

msg=0x2F78370
000408: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_is_valid_ccb:
000409: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_is_valid_ccb:exit@8232:
000410: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/act_timer_remove_transaction:
000411: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_is_valid_ccb:
000412: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_is_valid_ccb:exit@8232:
000413: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_delete_ccb:
000414: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIRemoveChildren:
000415: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIUfreeOneCCB:
000416: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIUdeleteccCallIdFromTable:
000417: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Info/verbose/4096/sipSPIUdeleteccCallIdFrom

Table: Removing call id 9
000418: *Nov 21 15:36:41.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIDeleteContextFromTabl

e: Context for key=[6] removed.
000419: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIStopFinalStatsCleanupTimer:
000420: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIStopCommonTimer:
000421: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIUdeleteCcbFromUASReqTable:
000422: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIUdeleteCcbFromTable:
000423: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Info/verbose/4096/sipSPIUdeleteCcbFromTable

: Deleting from table. ccb=0x42F2E478 key=f8a5c5ea-79450263-

77761f98-5cf9a365@130.9.112.752802f8a5c5ea794500533d2cbee0-

5a38c860
000424: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIUdeleteCcbFromUASRespTable:
000425: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Info/verbose/4096/sipSPIFlushEventBufferQue

ue: There are 0 events on the internal queue that are going to

be free'd
000426: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Error/sipSPIFlushDeferredQueue:
 Invalid deferredQueue
000427: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIFreeSessionTargetInfo:
000428: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIFreeHistoryInfo:
000429: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPICleanupContainerBlock:
000430: *Nov 21 15:36:41.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtI

nConnection: gConnTab=0x423A9DDC, addr=130.9.112.75, port=5060,

local_addr=130.9.200.114, unregistering context=0x42F2E478
000431: *Nov 21 15:36:41.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection

: Purging context gcb=0x42F2E478 from the connection=0x423AA3BC

context list
000432: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_platform_delete_ccb:
000433: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_platform_stop_timers:
000434: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIDropRtpSessions:
000435: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIGetFirstStream:
000436: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIGetMainStream:
000437: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_qos_cleanup:
000438: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIGetFirstStream:
000439: *Nov 21 15:36:41.927 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed

msg=0x2C52B98
000440: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_platform_terminate_implicit_

subscriptions:
000441: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_free_kpml_info:
000442: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_free_kpml_info:exit@1508:
000443: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIFlushKpmlDigitBuffer:
000444: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIFlushKpmlDigitBuffer:exit@20

39:
000445: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPI_ipip_free_dialpeer_video_ca

ps:
000446: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPI_ipip_free_codec_profile:
000447: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Info/verbose/1/sipSPI_ipip_free_codec_profi

le: Codec Profiles Freed
000448: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPICleanupOverlapInfo:
000449: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIStopOverlapInfoTimer:
000450: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIStopCommonTimer:
000451: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Info/info/8192/sipSPIStopOverlapInfoTimer:

Stopping Overlap Info Timer
000452: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIBwCacReleaseAccountedBw:
000453: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/sipSPIBwCacReleaseAccountedBw:exit

@24092:
000454: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Function/ccsip_clear_rtp_session_pending_q:
000455: *Nov 21 15:36:41.927 UAE:

//9/01314DE18007/SIP/Info/verbose/4096/sipSPIUfreeOneCCB:

Freeing ccb 0x42F2E478
000456: *Nov 21 15:37:02.583 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnAgeing:

Holder=0x45FB9EAC Connection=0x423AA3BC, addr=130.9.112.75,

port=5060, connid=3 has been REFRESHED
000457: *Nov 21 15:37:02.583 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging

timer initiated for holder=0x45FB9EAC,addr=130.9.112.75
SGHSHJ-MDF-VG01#
000458: *Nov 21 15:37:31.811 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnAgeing:

Connection=0x1574BED4, addr=130.9.112.80, port=5060, connid=4

has been aged out
000459: *Nov 21 15:37:31.811 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostCloseConnection:

Posting UDP conn close for addr=130.9.112.80, port=5060,

local_addr=130.9.200.114, connid=4
000460: *Nov 21 15:37:31.811 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipDeleteConnInstance: Deleted

conn=0x1574BED4, connid=4, addr=130.9.112.80, port=5060,

local_addr=130.9.200.114, transport=UDP
000461: *Nov 21 15:37:31.811 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging

timer initiated for holder=0x1721DDD4,addr=130.9.112.80
000462: *Nov 21 15:37:31.811 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipTransportStopAgeingTimer: Aging

timer stopped for holder=0x1721DDD4,addr=130.9.112.80
000463: *Nov 21 15:37:31.811 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipDeleteConnHolder: Deleted

holder=0x1721DDD4, addr=130.9.112.80, count=0
000464: *Nov 21 15:37:31.811 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/512/udpsock_close_connect: Socket

fd: 2 closed for connid 4 with remote port: 5060
SGHSHJ-MDF-VG01#
000465: *Nov 21 15:38:16.447 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnAgeing:

Connection=0x254D774, addr=130.9.112.74, port=5060, connid=5 has

been aged out
000466: *Nov 21 15:38:16.447 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostCloseConnection:

Posting UDP conn close for addr=130.9.112.74, port=5060,

local_addr=130.9.200.114, connid=5
000467: *Nov 21 15:38:16.447 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipDeleteConnInstance: Deleted

conn=0x254D774, connid=5, addr=130.9.112.74, port=5060,

local_addr=130.9.200.114, transport=UDP
000468: *Nov 21 15:38:16.447 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging

timer initiated for holder=0x1721D88C,addr=130.9.112.74
000469: *Nov 21 15:38:16.447 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipTransportStopAgeingTimer: Aging

timer stopped for holder=0x1721D88C,addr=130.9.112.74
000470: *Nov 21 15:38:16.447 UAE: //-

1/xxxxxxxxxxxx/SIP/Transport/sipDeleteConnHolder: Deleted

holder=0x1721D88C, addr=130.9.112.74, count=0
000471: *Nov 21 15:38:16.447 UAE: //-

1/xxxxxxxxxxxx/SIP/Info/info/512/udpsock_close_connect: Socket

fd: 3 closed for connid 5 with remote port: 5060
SGHSHJ-MDF-VG01#

0 Helpful

Rajkumar Yadav
Level 4
Level 4
In response to Vishal Parekh

‎11-21-2017 06:42 AM

Hi,

 

voice register pool  1
 id network 130.9.200.114 mask 255.255.255.252 // this is wrong values for the id network
 dtmf-relay rtp-nte sip-notify
 codec g711ulaw
 no vad

 

id network command should have the ip phone subnet value.

id network 192.168.25.0 mask 255.255.255.0

where 192.168.25.0 is the voice subnet for the ip phone at that site.

 

Regards,

Raaj

0 Helpful
Customers Also Viewed These Support Documents