10-09-2018 04:11 AM - edited 10-09-2018 04:24 AM
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
10-09-2018 04:22 AM - edited 10-09-2018 04:23 AM
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
04-29-2019 06:54 AM
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
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: