SIP_Gateway#sh debu SIP_Gateway#sh debugging CCSIP SPI: SIP Call Statistics tracing is enabled (filter is OFF) SIP_Gateway# SIP_Gateway# SIP_Gateway# SIP_Gateway#debug ccsip all This may severely impact system performance. Continue? [confirm] All SIP Call tracing is enabled SIP_Gateway# *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.xx.xx.214]:5060 *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0 *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: OPTIONS sip:10.xx.xx.xx:5060 SIP/2.0 Via: SIP/2.0/UDP 10.xx.xx.214:5060;branch=z9hG4bK363m60303og0fm82k5n0.1 From: ;tag=56600B0A-7C84AF7-3E47F5DB To: Call-ID: 56600B0A-00CAA194@vPCU2 CSeq: 868 OPTIONS Supported: timer Max-Forwards: 69 Content-Length: 0 *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x40AE8740) with key=[12559] to table *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.xx.xx.214,Port 5060, Transport 1, SentBy Port 5060 *Dec 3 09:22:39 UTC: //-1/3B59532DB2D9/SIP/State/sipSPIChangeState: 0x40AE8740 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.xx.xx.214,Port 5060, Transport 1, SentBy Port 5060 *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.xx.xx.214,Port 5060, Transport 1, SentBy Port 5060 *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetFromCalledPartyId: P-Called-Party-ID header not found *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetPeerByCalledPartyId: P-Called-Party-ID not found or parse error *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: No match found for P-Called-Party-ID *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18 *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0 *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8 *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 9 *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4 *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: R12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15 *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 255 *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Error/rtpAvpCodec_to_voipCodec: Unexpected RTP PayloadType :255 in SDP Body *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.xx.xx.xxTP Preferred Codecs supported by GW 2 *Dec 3 09:22:39 UTC: //*Adding to UAS Request table. *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x40AE8740 key=56600B0A-00CAA194@vPCU2 *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/State/sipSPIChangeState: 0x40AE8740 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE) *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIUaddCcbToUASReqTable: *** *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 3171 to table *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38 *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPISendOptionsResponse: Associated container=0x307B9428 to Options Response *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Transport/sipSPITransportSendMessage: msg=0x30DB221C, addr=10.xx.xx.214, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x249B758C *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately *Dec 3 09:22:39 UTC: //12657/3B59532DB2D9/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0 *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x30DB221C, addr=10.xx.xx.214, port=5060, connId=0 for UDP *Dec 3 09:22:39 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.xx.xx.214:5060;branch=z9hG4bK363m60303og0fm82k5n0.1 From: ;tag=56600B0A-7C84AF7-3E47F5DB To: ;tag=48879428-1DB4 Date: Thu, 03 Dec 2015 09:22:39 gmt Call-ID: 56600B0A-00CAA194@vPCU2 Server: Cisco-SIPGateway/IOS-12.x CSeq: 868 OPTIONS Supported: 100rel,resource-priority,replaces,sdp-anat Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Allow-Events: telephone-event Accept: application/sdp Content-Type: application/sdp Content-Length: 441 v=0 o=CiscoSystemsSIP-GW-UserAgent 61 0 IN IP4 10.xx.xx.xx s=SIP Call c=IN IP4 10.xx.xx.xx t=0 0 m=audio 0 RTP/AVP 18 0 8 9 4 2 15 c=IN IP4 10.xx.xx.xx m=image 0 udptl t38 c=IN IP4 10.xx.xx.xx a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxFillBitRemoval:0 a=T38FaxTranscodingMMR:0 a=T38FaxTranscodingJBIG:0 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:200 a=T38FaxMaxDatagram:180 a=T38FaxUdpEC:t38UDPRedundancy *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x40ADE120) with key=[12560] to table *Dec 3 09:22:44 UTC: //12659/000000000000/SIP/State/sipSPIChangeState: 0x40ADE120 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) *Dec 3 09:22:44 UTC: //12659/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200 *Dec 3 09:22:44 UTC: //12659/000000000000/SIP/Info/ccsip_call_setup_request: This is a TDM-IP call: callID= 12659, peer_callID = 12658 *Dec 3 09:22:44 UTC: //12659/000000000000/SIP/Info/ccsip_call_setup_request: This is a TDM-IP call: callID= 12659, peer_callID = 12658 *Dec 3 09:22:44 UTC: //12659/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 10.xx.xx.214 target_port : 5060 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/ccsip_call_setup_request: Incrementing call counter in dial-peer [1001] *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 3173 to table *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIGetCallConfig: Media forking disabled *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIGetCallConfig: Not using Voice Class Codec *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIGetCallConfig: Checking Video Type Rate=-1 video_codec_allowed=1F *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIGetCallConfig: xcoder high-density disabled *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIGetCallConfig: Flow Mode set to FLOW_THROUGH *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIGetCallConfig: Media forking disabled *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/preprocessSetup: This is a not a SIGO Call -, could be DM call *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.xx.xx.xx *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 17650 for stream 1 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPIProcessRtpSessions: No active streams. *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIValidateGtd: Signal Forward disabled *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIShrlGetInstanceInfo: Obtained the call instance 0 for non-shared-line '0044.........' with callid: 12659 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header. *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Error/sipSPI_ipip_set_history_info_header: Not SIP2SIP mode *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/act_idle_continue_call_setup: *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table. *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x40ADE120 key=3E526F35-98D611E5-B2DFA8CB-2EF215D1@10.xx.xx.xx *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = 3E526F35-98D611E5-B2DFA8CB-2EF215D1@10.xx.xx.xx *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.xx.xx.214,Port 5060, Transport 1, SentBy Port 5060 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Event/sipSPICreateRpid: Received Octet3A=0x81 -> Setting ;screen=yes ;privacy=off SIP: (12659) Group (a= group line) attribute, level 65535 instance 1 not found. *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPISendInvite: Associated container=0x307B9848 to Invite *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipSPITransportSendMessage: msg=0x31159204, addr=10.xx.xx.214, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x249B44AC *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging timer initiated for holder=0x3007C2E4,addr=10.xx.xx.214 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipCreateConnHolder: Created new holder=0x3007C2E4, addr=10.xx.xx.214; nailed=FALSE *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostRequestConnection: Posting UDP conn create request for addr=10.xx.xx.214, port=5060, context=0x308A6200 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetConnWaitTimer: Wait timer set for connection=0x308A7354,addr=10.xx.xx.214, port=5060 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetConnWaitTimer: Wait Conn Timer started for 5000 msec *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipCreateConnInstance: Created new initiated conn=0x308A7354, connid=-1, addr=10.xx.xx.214, port=5060, transport=UDP *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x40ADE120 with connection=0x308A7354 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipSPITransportSendMessage: Deferred sending msg=0x31159204 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 57 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWConnectionCreated: context=0x308A6200 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessConnCreated: gConnTab=0x308A6200, addr=10.xx.xx.214, port=5060, connid=2, transport=UDP *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportStopConnWaitTimer: Wait timer stopped for connection=0x308A7354,addr=10.xx.xx.214, port=5060 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnectionCreated: Moving connection=0x308A7354, connid=2state to established *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipTransportPostInternalMsg: Posting Internal Msg type=0 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 62 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x31159204, addr=10.xx.xx.214, port=5060, connId=2 for UDP *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued. *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/State/sipSPIChangeState: 0x40ADE120 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE) *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 12659) to the VOIP RTP library *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.xx.xx.xx *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info laddr = 10.xx.xx.xx, lport = 17650, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE src_callid = 12659, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY media_ip_addr = - , vrf tableid = 0 media_addr_type = 1 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPICreateRtpSession: sess: 30DB221C do_rtcp:0 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPICreateRtpSession: stun is disabled *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/State/sipSPIChangeStreamState: Stream (callid = 12659) State changed from (STREAM_ADDING) to (STREAM_ACTIVE) *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: INVITE sip:003xxxxxxxxxxx@10.xx.xx.214:5060 SIP/2.0 Via: SIP/2.0/UDP 10.xx.xx.xx:5060;branch=z9hG4bK62525 Remote-Party-ID: ;party=calling;screen=yes;privacy=off From: ;tag=4887A7A4-3C2 To: Date: Thu, 03 Dec 2015 09:22:44 gmt Call-ID: 3E526F35-98D611E5-B2DFA8CB-2EF215D1@10.xx.xx.xx Supported: 100rel,timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Cisco-Guid: 1045509861-2564166117-2153367708-0495126304 User-Agent: Cisco-SIPGateway/IOS-12.x Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Max-Forwards: 70 Timestamp: 1449134564 Contact: Expires: 180 Allow-Events: telephone-event Content-Type: application/sdp Content-Disposition: session;handling=required Content-Length: 188 v=0 o=CiscoSystemsSIP-GW-UserAgent 5454 8838 IN IP4 10.xx.xx.xx s=SIP Call c=IN IP4 10.xx.xx.xx t=0 0 m=audio 17650 RTP/AVP 8 c=IN IP4 10.xx.xx.xx a=rtpmap:8 PCMA/8000 a=ptime:20 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Error/get_content_length: Could not get Content-length *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.xx.xx.214]:5060 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.xx.xx.xx:5060;branch=z9hG4bK62525 From: ;tag=4887A7A4-3C2 To: Call-ID: 3E526F35-98D611E5-B2DFA8CB-2EF215D1@10.xx.xx.xx CSeq: 101 INVITE Timestamp: 1449134564 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/State/sipSPIChangeState: 0x40ADE120 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STAT SIP_Gateway#E_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.xx.xx.214]:5060 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialunog *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 400 Bad Request Via: SIP/2.0/UDP 10.xx.xx.xx:5060;branch=z9hG4bK62525 From: ;tag=4887A7A4-3C2 To: ;tag=3337963326-1208552058 Call-ID: 3E526F35-98D611E5-B2DFA8CB-2EF215D1@10.xx.xx.xx CSeq: 101 INVITE Timestamp: 1449134564 Content-Length: 0 P-Asserted-Identity: *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPICheckResponseExt: INVITE response with all All possible debugging has been turned off SIP_Gateway#no RSEQ - disable IS_REL1XX *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPISendAck: Associated container=0x307B8FB0 to Ack *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipSPITransportSendMessage: msg=0x40CC8804, addr=10.xx.xx.214, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x0 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x40CC8804 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x40CC8804, addr=10.xx.xx.214, port=5060, connId=2 for UDP *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/act_recdproc_new_message_response: Received a 4/5/6xx message with StatusCode: 400 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[12659], src[4] *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIInitiateDisconnect: Initiaute call disconnect(127) for outgoing call *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/State/sipSPIChangeState: 0x40ADE120 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_DISCONNECTING, SUBSTATE_NONE) *Dec 3 09:22:44 UTC: //12659/3E5136E5805n all All possible debugging has been turned off SIP_Gateway#9/SIP/Info/ccsip_call_statistics: Requesting stats for callid=12659 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/ccsip_call_statistics: Stats request failed for callid=12659, dstCallID=-1, rc=-7 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIIcpifUpdate: CallState: 2 Playout: 0 DiscTime:121684985 ConnTime 0 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Media/sipSPIDestroyRtpSession: stream:310B47B4 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/State/sipSPIChangeState: 0x40ADE120 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE) *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Call/sipSPICallInfo: The Call Setup Information is: Call Control Block (CCB) : 0x40ADE120 State of The Call : STATE_DEAD TCP Sockets Used : NO Calling Number : +358xxxxxxxxx Called Number : 003xxxxxxxxxxx Source IP Address (Sig ): 10.xx.xx.xx Destn SIP Req Addr:Port : 10.xx.xx.214:5060 Destn SIP Resp Addr:Port : 10.xx.xx.214:5060 Destination Name : 10.xx.xx.214 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Call/sipSPIMediaCallInfo: Number of Media Streams: 1 Media Stream : 1 Negotiated Codec : No Codec Negotiated Codec Bytes : 0 Nego. Codec payload : 255 (tx), 255 (rx) Negotiated Dtmf-relay : 0 Dtmf-relay Payload : 0 (tx), 0 (rx) Source IP Address (Media): 10.xx.xx.xx Source IP Port (Media): 17650 Destn IP Address (Media): - Destn IP Port (Media): 0 Orig Destn IP Address:Port (Media): [ - ]:0 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Call/sipSPICallInfo: Disconnect Cause (CC) : 127 Disconnect Cause (SIP) : 400 *Dec 3 09:22:44 UTC: //12659/3E5136E58059/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3173 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x40AD8E10) with key=[12561] to table *Dec 3 09:22:44 UTC: //12660/000000000000/SIP/State/sipSPIChangeState: 0x40AD8E10 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) *Dec 3 09:22:44 UTC: //12660/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200 *Dec 3 09:22:44 UTC: //12660/000000000000/SIP/Info/ccsip_call_setup_request: This is a TDM-IP call: callID= 12660, peer_callID = 12658 *Dec 3 09:22:44 UTC: //12660/000000000000/SIP/Info/ccsip_call_setup_request: This is a TDM-IP call: callID= 12660, peer_callID = 12658 *Dec 3 09:22:44 UTC: //12660/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 10.xx.xx.214 target_port : 5060 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/ccsip_call_setup_request: Incrementing call counter in dial-peer [1000] *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 3174 to table *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIGetCallConfig: Media forking disabled *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIGetCallConfig: xcoder high-density disabled *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIGetCallConfig: Flow Mode set to FLOW_THROUGH *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIGetCallConfig: Media forking disabled *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/preprocessSetup: This is a not a SIGO Call -, could be DM call *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.xx.xx.xx *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 18972 for stream 1 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPIProcessRtpSessions: No active streams. *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIValidateGtd: Signal Forward disabled *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIShrlGetInstanceInfo: Obtained the call instance 0 for non-shared-line '.T' with callid: 12660 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header. *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Error/sipSPI_ipip_set_history_info_header: Not SIP2SIP mode *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/act_idle_continue_call_setup: *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table. *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x40AD8E10 key=3E57510D-98D611E5-B2E1A8CB-2EF215D1@10.xx.xx.xx *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = 3E57510D-98D611E5-B2E1A8CB-2EF215D1@10.xx.xx.xx *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.xx.xx.214,Port 5060, Transport 1, SentBy Port 5060 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Event/sipSPICreateRpid: Received Octet3A=0x81 -> Setting ;screen=yes ;privacy=off SIP: (12660) Group (a= group line) attribute, level 65535 instance 1 not found. *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPISendInvite: Associated container=0x307B9270 to Invite *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Transport/sipSPITransportSendMessage: msg=0x40CC8CA8, addr=10.xx.xx.214, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x249B44AC *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x40CC8CA8 *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x40CC8CA8, addr=10.xx.xx.214, port=5060, connId=2 for UDP *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE *Dec 3 09:22:44 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued. *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/State/sipSPIChangeState: 0x40AD8E10 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE) *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 12660) to the VOIP RTP library *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.xx.xx.xx *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info laddr = 10.xx.xx.xx, lport = 18972, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE src_callid = 12660, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY media_ip_addr = - , vrf tableid = 0 media_addr_type = 1 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Info/sipSPICreateRtpSession: sess: 3007C3C8 do_rtcp:0 *Dec 3 09:22:44 UTC: //12660/3E5136E58059/SIP/Media/sipSPICreateRtpSession: stun is disabled126304 User-Agent: Cisco-SIPGateway/IOS-12.x Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Max-Forwards: 70 Timestamp: 1449134564 Contact: Expires: 180 Allow-Events: telephone-event Content-Type: application/sdp Content-Disposition: session;handling=required Content-Length: 255 v=0 o=CiscoSystemsSIP-GW-UserAgent 554 5165 IN IP4 10.xx.xx.xx s=SIP Call c=IN IP4 10.xx.xx.xx t=0 0 m=audio 18972 RTP/AVP 8 0 101 c=IN IP4 10.xx.xx.xx a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 126304 User-Agent: Cisco-SIPGateway/IOS-12.x Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Max-Forwards: 70 Timestamp: 1449134565 Contact: Expires: 180 Allow-Events: telephone-event Content-Type: application/sdp Content-Disposition: session;handling=required Content-Length: 255 v=0 o=CiscoSystemsSIP-GW-UserAgent 554 5165 IN IP4 10.xx.xx.xx s=SIP Call c=IN IP4 10.xx.xx.xx t=0 0 m=audio 18972 RTP/AVP 8 0 101 c=IN IP4 10.xx.xx.xx a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 *Dec 3 09:22:51 UTC: //12656/2F79C616B2D8/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3170 *Dec 3 09:22:51 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[12558] removed. *Dec 3 09:22:51 UTC: //12656/2F79C616B2D8/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table. *Dec 3 09:22:51 UTC: //12656/2F79C616B2D8/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x40AE3430 key=56600AF6-00CAA109@vPCU2 *Dec 3 09:22:51 UTC: //12656/2F79C616B2D8/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table. *Dec 3 09:22:51 UTC: //12656/2F79C616B2D8/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd *Dec 3 09:22:51 UTC: //12656/2F79C616B2D8/SIP/Info/ccsip_qos_cleanup: Entry *Dec 3 09:22:51 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISipSdpFree: *Dec 3 09:22:51 UTC: //12656/2F79C616B2D8/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed *Dec 3 09:22:51 UTC: //12656/2F79C616B2D8/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 40AE3430 *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.xx.xx.214]:5060 *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0 *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: OPTIONS sip:10.xx.xx.xx:5060 SIP/2.0 Via: SIP/2.0/UDP 10.xx.xx.214:5060;branch=z9hG4bKlhc8cq302gc08lgto6g1.1 From: ;tag=56600B1E-7C84FF4-3E47F5DB To: Call-ID: 56600B1E-00CAA216@vPCU2 CSeq: 959 OPTIONS Supported: timer Max-Forwards: 69 Content-Length: 0 *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x40AE3430) with key=[12562] to table *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.xx.xx.214,Port 5060, Transport 1, SentBy Port 5060 *Dec 3 09:22:59 UTC: //-1/473A192DB2E2/SIP/State/sipSPIChangeState: 0x40AE3430 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.xx.xx.214,Port 5060, Transport 1, SentBy Port 5060 *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.xx.xx.214,Port 5060, Transport 1, SentBy Port 5060 *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetFromCalledPartyId: P-Called-Party-ID header not found *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetPeerByCalledPartyId: P-Called-Party-ID not found or parse error *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: No match found for P-Called-Party-ID *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18 *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0 *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8 *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 9 *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4 *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2 *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15 *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 255 *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Error/rtpAvpCodec_to_voipCodec: Unexpected RTP PayloadType :255 in SDP Body *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.xx.xx.xx *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/State/sipSPIChangeState: 0x40AE3430 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE) *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table. *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x40AE3430 key=56600B1E-00CAA216@vPCU2 *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 3175 to table *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38 *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Info/sipSPISendOptionsResponse: Associated container=0x307B9E20 to Options Response *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Transport/sipSPITransportSendMessage: msg=0x40CC8CA8, addr=10.xx.xx.214, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x249B758C *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately *Dec 3 09:22:59 UTC: //12661/473A192DB2E2/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0 *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x40CC8CA8, addr=10.xx.xx.214, port=5060, connId=0 for UDP *Dec 3 09:22:59 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: *Dec 3 09:23:11 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3171 *Dec 3 09:23:11 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[12559] removed. *Dec 3 09:23:11 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table. *Dec 3 09:23:11 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x40AE8740 key=56600B0A-00CAA194@vPCU2 *Dec 3 09:23:11 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table. *Dec 3 09:23:11 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd *Dec 3 09:23:11 UTC: //12657/3B59532DB2D9/SIP/Info/ccsip_qos_cleanup: Entry *Dec 3 09:23:11 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISipSdpFree: *Dec 3 09:23:11 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed *Dec 3 09:23:11 UTC: //12657/3B59532DB2D9/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 40AE8740 *Dec 3 09:23:16 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[12560] removed. *Dec 3 09:23:16 UTC: //12659/3E5136E58059/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table. *Dec 3 09:23:16 UTC: //12659/3E5136E58059/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x40ADE120 key=3E526F35-98D611E5-B2DFA8CB-2EF215D1@10.xx.xx.xx *Dec 3 09:23:16 UTC: //12659/3E5136E58059/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd *Dec 3 09:23:16 UTC: //12659/3E5136E58059/SIP/Info/ccsip_qos_cleanup: Entry *Dec 3 09:23:16 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISipSdpFree: *Dec 3 09:23:16 UTC: //12659/3E5136E58059/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed *Dec 3 09:23:16 UTC: //12659/3E5136E58059/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 40ADE120 *Dec 3 09:23:17 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[12561] removed. *Dec 3 09:23:17 UTC: //12660/3E5136E58059/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table. *Dec 3 09:23:17 UTC: //12660/3E5136E58059/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x40AD8E10 key=3E57510D-98D611E5-B2E1A8CB-2EF215D1@10.xx.xx.xx *Dec 3 09:23:17 UTC: //12660/3E5136E58059/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd *Dec 3 09:23:17 UTC: //12660/3E5136E58059/SIP/Info/ccsip_qos_cleanup: Entry *Dec 3 09:23:17 UTC: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISipSdpFree: *Dec 3 09:23:17 UTC: //12660/3E5136E58059/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed *Dec 3 09:23:17 UTC: //12660/3E5136E58059/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 40AD8E10 SIP_Gateway# SIP_Gateway#