cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
827
Views
0
Helpful
2
Replies

Cisco 3945 - Resource failure, dropping OPTIONS messages

PiotrOSki
Level 1
Level 1

Hi
 
I currently have a 3945 with ios Version 15.3(3)M5.
I have an issue with SIP OPTIONS ping messages coming from endpoint.

In one second cube receive > 5 SIP OPTIONS ping in one second (coming from the same IP) and only for first option packet cisco sent response OK, and other OPTION messages are dropped with  :

52:06 10.10.10.10 8694907: Oct  9 10:55:58.194: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
Oct  9 12:52:06 10.10.10.10 8694908: Oct  9 10:55:58.194: //148025/BCB3C79A92C0/SIP/Info/info/4096/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS
Oct  9 12:52:06 10.10.10.10 8694909: Oct  9 10:55:58.194: //148025/BCB3C79A92C0/SIP/State/sipSPIChangeState: 0x258D7210 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
Oct  9 12:52:06 10.10.10.10 8694910: Oct  9 10:55:58.194: //148025/BCB3C79A92C0/SIP/Error/sipSPIUaddCcbToTable:
Oct  9 12:52:06 10.10.10.10 8694911:  Could not add ccb to table. ccb=0x258D7210 key=c3c4f5582a4bfa1ff4b7e741c3cb6c6822f738b4cd7e78633fc70f5441197d3
Oct  9 12:52:06 10.10.10.10 8694912: Oct  9 10:55:58.194: //148025/BCB3C79A92C0/SIP/Error/sact_idle_new_message_options:
Oct  9 12:52:06 10.10.10.10 8694913:  Resource failure, dropping OPTIONS

 

Could someone help with this? I really appreciate for advice.

CUBE need to send response for each incoming OPTION message

 

2 Replies 2

PiotrOSki
Level 1
Level 1

And example for successfull 200 OK sent for OPTION

 
Oct  9 11:30:37 10.10.10.10 8625106: Oct  9 09:34:28.569: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
Oct  9 11:30:37 10.10.10.10 8625107: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/Info/info/4096/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS
Oct  9 11:30:37 10.10.10.10 8625108: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/State/sipSPIChangeState: 0x258B1110 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
Oct  9 11:30:37 10.10.10.10 8625109: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x258B1110 key=c3c4f5582a4bfa1ff4b7e741c3cb6c6822f738b4cd7e78633fc70f5441197d3 balance 1
Oct  9 11:30:37 10.10.10.10 8625110: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/Info/verbose/4096/sipSPIUaddccCallIdToTable: Adding call id 23DA9 to table
Oct  9 11:30:37 10.10.10.10 8625111: Oct  9 09:34:28.569: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38
Oct  9 11:30:37 10.10.10.10 8625112: Oct  9 09:34:28.569: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x203FFDA4 with refCount = 1
Oct  9 11:30:37 10.10.10.10 8625113: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/Info/info/4096/sipSPISendOptionsResponse: Associated container=0x2673A528 to Options Response
Oct  9 11:30:37 10.10.10.10 8625114: Oct  9 09:34:28.569: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 164
Oct  9 11:30:37 10.10.10.10 8625115: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/Transport/sipSPITransportSendMessage: msg=0x203FFDA4, addr=11.11.11.11, port=5060, sentBy_port=5060, local_addr=, is_req=0, transport=1, switch=0, callBack=0x4F48054
Oct  9 11:30:37 10.10.10.10 8625116: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Oct  9 11:30:37 10.10.10.10 8625117: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Oct  9 11:30:37 10.10.10.10 8625118: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x203FFDA4 to default port=5060
Oct  9 11:30:37 10.10.10.10 8625119: Oct  9 09:34:28.569: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:11.11.11.11, rport:5060 with laddr:
Oct  9 11:30:37 10.10.10.10 8625120: Oct  9 09:34:28.569: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x258B1110 with connection=0x2426673C context list
Oct  9 11:30:37 10.10.10.10 8625121: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x203FFDA4
Oct  9 11:30:37 10.10.10.10 8625122: Oct  9 09:34:28.569: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x203FFDA4, addr=11.11.11.11, port=5060, local_addr=, connId=2 for UDP
Oct  9 11:30:37 10.10.10.10 8625123: Oct  9 09:34:28.569: //146857/5A42A0608E30/SIP/Msg/ccsipDisplayMsg:
Oct  9 11:30:37 10.10.10.10 8625124: Sent:
Oct  9 11:30:37 10.10.10.10 8625125: SIP/2.0 200 OK#015

 

 

I am experiencing this very same issue on a 2921.  Did you ever find a resolution?

 

Todd Boynton
University of Maine System
Unified Communications Analyst
US:IT
Networkmaine

tboynton@maine.edu

Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: