Router#deb ccsip all This may severely impact system performance. Continue? [confirm]yAll SIP Call tracing is enabled Router# Router# Router# Router# Router# Router# Router# Router#term moni Router# Router# Router# Router# Router# Router# 020936: Feb 18 17:47:07.381 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x493CF590) with key=[1236] to table 020937: Feb 18 17:47:07.381 EST: //2131/000000000000/SIP/State/sipSPIChangeState: 0x493CF590 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 020938: Feb 18 17:47:07.381 EST: //2131/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200 020939: Feb 18 17:47:07.381 EST: //2131/000000000000/SIP/Info/ccsip_call_setup_request: This is a TDM-IP call: callID= 2131, peer_callID = 2130 020940: Feb 18 17:47:07.381 EST: //2131/000000000000/SIP/Info/ccsip_call_setup_request: This is a TDM-IP call: callID= 2131, peer_callID = 2130 020941: Feb 18 17:47:07.381 EST: //2131/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200 020942: Feb 18 17:47:07.381 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : outbound.domain.com target_port : 5060 020943: Feb 18 17:47:07.381 EST: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP 020944: Feb 18 17:47:07.385 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2 020945: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 853 to table 020946: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0 020947: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active 020948: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1 020949: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Media/sipSPICopyPeerDataToCCB: Firewall traversal is not enabled 020950: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPIGetCallConfig: xcoder high-density disabled 020951: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPIGetCallConfig: Flow Mode set to FLOW_THROUGH 020952: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/preprocessSetup: This is a not a SIGO Call -, could be DM call 020953: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host 020954: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sip_gw_pre_setup_add_sdp_container: DNS/ENUM resolution required; Deferred Creating SDP 020955: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPIValidateGtd: Signal Forward disabled 020956: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled 020957: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg 020958: Feb 18 17:47:07.385 EST: //2131/E418CF34942C/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header. 020959: Feb 18 17:47:07.389 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddPrivacyandIdentityInfo: ccb->local_host_name,ccb->src_addr_str is NULL 020960: Feb 18 17:47:07.389 EST: //2131/E418CF34942C/SIP/Error/sipSPI_ipip_set_history_info_header: Not SIP2SIP mode 020961: Feb 18 17:47:07.389 EST: //2131/E418CF34942C/SIP/Info/act_idle_continue_call_setup: 020962: Feb 18 17:47:07.389 EST: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_DNS_RESOLVE 020963: Feb 18 17:47:07.389 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeState: 0x493CF590 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_SENT_DNS) 020964: Feb 18 17:47:07.389 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeState: 0x493CF590 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (STATE_IDLE, SUBSTATE_SENT_DNS) 020965: Feb 18 17:47:07.389 EST: //2131/E418CF34942C/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table. 020966: Feb 18 17:47:07.389 EST: //2131/E418CF34942C/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x493CF590 key=E776A57C-FD4411DD-9431EE7C-DC4778E6@1.1.1.1 020967: Feb 18 17:47:07.389 EST: //2131/E418CF34942C/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = E776A57C-FD4411DD-9431EE7C-DC4778E6@1.1.1.1 020968: Feb 18 17:47:07.405 EST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: TYPE SRV query for _sip._udp.outbound.domain.com and type:1 020969: Feb 18 17:47:08.173 EST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: DNS query for outbound.domain.com and type:1 020970: Feb 18 17:47:08.173 EST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_query: TYPE A query successful for outbound.domain.com 020971: Feb 18 17:47:08.173 EST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: IP Address of outbound.domain.com is: 020972: Feb 18 17:47:08.173 EST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: 2.2.2.2 020973: Feb 18 17:47:08.173 EST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: 2.2.2.3 020974: Feb 18 17:47:08.173 EST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: 2.2.2.4 020975: Feb 18 17:47:08.173 EST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: 2.2.2.5 020976: Feb 18 17:47:08.177 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 43 020977: Feb 18 17:47:08.177 EST: //2131/E418CF34942C/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0 020978: Feb 18 17:47:08.177 EST: //2131/E418CF34942C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 1.1.1.1 020979: Feb 18 17:47:08.177 EST: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 17288 for stream 1 020980: Feb 18 17:47:08.177 EST: //2131/E418CF34942C/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101 020981: Feb 18 17:47:08.177 EST: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20 020982: Feb 18 17:47:08.177 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1 020983: Feb 18 17:47:08.177 EST: //2131/E418CF34942C/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed 020984: Feb 18 17:47:08.177 EST: //2131/E418CF34942C/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call 020985: Feb 18 17:47:08.177 EST: //2131/E418CF34942C/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions 020986: Feb 18 17:47:08.177 EST: //2131/E418CF34942C/SIP/Media/sipSPIProcessRtpSessions: No active streams. 020987: Feb 18 17:47:08.181 EST: //2131/E418CF34942C/SIP/Error/sipSPI_ipip_set_history_info_header: Not SIP2SIP mode 020988: Feb 18 17:47:08.181 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 2.2.2.2,Port 5060, Transport 1, SentBy Port 5060 020989: Feb 18 17:47:08.181 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone EST to SIP default timezone = GMT 020990: Feb 18 17:47:08.181 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured 020991: Feb 18 17:47:08.181 EST: //2131/E418CF34942C/SIP/Event/sipSPICreateRpid: Received Octet3A=0x83 -> Setting ;screen=yes ;privacy=off SIP: (2131) Group (a= group line) attribute, level 65535 instance 1 not found. 020992: Feb 18 17:47:08.181 EST: //2131/E418CF34942C/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat 020993: Feb 18 17:47:08.181 EST: //2131/E418CF34942C/SIP/Info/sipSPISendInvite: Associated container=0x4B673444 to Invite 020994: Feb 18 17:47:08.181 EST: //2131/E418CF34942C/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer 020995: Feb 18 17:47:08.181 EST: //2131/E418CF34942C/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE 020996: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Transport/sipSPITransportSendMessage: msg=0x4BEA6F48, addr=2.2.2.2, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x416E22E4 020997: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 020998: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0 020999: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x4BEA6F48 021000: Feb 18 17:47:08.185 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x4BEA6F48, addr=2.2.2.2, port=5060, connId=2 for UDP 021001: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE 021002: Feb 18 17:47:08.185 EST: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued. 021003: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeState: 0x493CF590 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (STATE_SENT_INVITE, SUBSTATE_NONE) 021004: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions 021005: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 2131) to the VOIP RTP library 021006: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr 021007: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 1.1.1.1 021008: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1 021009: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info laddr = 1.1.1.1, lport = 17288, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE src_callid = 2131, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY media_ip_addr = - , vrf tableid = 0 media_addr_type = 1 021010: Feb 18 17:47:08.185 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one 021011: Feb 18 17:47:08.189 EST: //2131/E418CF34942C/SIP/Info/sipSPICreateRtpSession: sess: 4C1D4138 do_rtcp:0 021012: Feb 18 17:47:08.189 EST: //2131/E418CF34942C/SIP/Media/sipSPICreateRtpSession: stun is disabled 021013: Feb 18 17:47:08.189 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeStreamState: Stream (callid = 2131) State changed from (STREAM_ADDING) to (STREAM_ACTIVE) 021014: Feb 18 17:47:08.189 EST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: INVITE sip:4444444444@outbound.domain.com:5060 SIP/2.0 Date: Wed, 18 Feb 2009 22:47:08 GMT Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER From: "marian" ;tag=2A2FDDF4-242 Allow-Events: telephone-event Supported: 100rel,timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Remote-Party-ID: "marian" ;party=calling;screen=yes;privacy=off Cisco-Guid: 3826831156-4249096669-2485972604-3695671526 Timestamp: 1234997228 Content-Length: 250 User-Agent: Cisco-SIPGateway/IOS-12.x To: Contact: Expires: 180 Content-Disposition: session;handling=required Content-Type: application/sdp Call-ID: E776A57C-FD4411DD-9431EE7C-DC4778E6@1.1.1.1 Via: SIP/2.0/UDP 1.1.1.1:5060;branch=z9hG4bKDE81E63 CSeq: 101 INVITE Max-Forwards: 70 v=0 o=CiscoSystemsSIP-GW-UserAgent 1734 3537 IN IP4 1.1.1.1 s=SIP Call c=IN IP4 1.1.1.1 t=0 0 m=audio 17288 RTP/AVP 0 101 c=IN IP4 1.1.1.1 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 021015: Feb 18 17:47:08.249 EST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [2.2.2.3]:5060 021016: Feb 18 17:47:08.249 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 021017: Feb 18 17:47:08.249 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x481FCF88 021018: Feb 18 17:47:08.249 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x481FCF88, addr=2.2.2.3, port=5060, connid=2, transport=UDP 021019: Feb 18 17:47:08.249 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 021020: Feb 18 17:47:08.249 EST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 100 Trying Via: SIP/2.0/UDP 1.1.1.1:5060;branch=z9hG4bKDE81E63;received=1.1.1.1 From: "marian" ;tag=2A2FDDF4-242 To: Call-ID: E776A57C-FD4411DD-9431EE7C-DC4778E6@1.1.1.1 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 021021: Feb 18 17:47:08.249 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeState: 0x493CF590 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) 021022: Feb 18 17:47:09.845 EST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [2.2.2.3]:5060 021023: Feb 18 17:47:09.853 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 021024: Feb 18 17:47:09.853 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x481FCF88 021025: Feb 18 17:47:09.853 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x481FCF88, addr=2.2.2.3, port=5060, connid=2, transport=UDP 021026: Feb 18 17:47:09.853 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 021027: Feb 18 17:47:09.853 EST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 1.1.1.1:5060;branch=z9hG4bKDE81E63;received=1.1.1.1 From: "marian" ;tag=2A2FDDF4-242 To: ;tag=as675e7559 Call-ID: E776A57C-FD4411DD-9431EE7C-DC4778E6@1.1.1.1 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 212 v=0 o=root 1065 1065 IN IP4 2.2.2.3 s=session c=IN IP4 2.2.2.3 t=0 0 m=audio 18914 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - 021028: Feb 18 17:47:09.853 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container 021029: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1 SIP: Attribute mid, level 1 instance 1 not found. 021030: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr 021031: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 1.1.1.1 021032: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoAudioNegotiation: Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1 021033: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled 021034: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1 021035: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(101) could not be reserved. 021036: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoDTMFRelayNegotiation: RTP-NTE DTMF relay option 021037: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events. 021038: Feb 18 17:47:09.857 EST: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0 021039: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay 021040: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0 021041: Feb 18 17:47:09.857 EST: //2131/E418CF34942C/SIP/Info/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=2.2.2.3, dest_port=18914 021042: Feb 18 17:47:09.861 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING) 021043: Feb 18 17:47:09.861 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdCallWithSdpInfo: Preferred Codec : g711ulaw, bytes :160 Preferred DTMF relay : rtp-nte Preferred NTE payload : 101 Early Media : No Delayed Media : No Bridge Done : No New Media : No DSP DNLD Reqd : No 021044: Feb 18 17:47:09.861 EST: //2131/E418CF34942C/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr 021045: Feb 18 17:47:09.861 EST: //2131/E418CF34942C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 1.1.1.1 021046: Feb 18 17:47:09.861 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdCallWithSdpInfo: Stream type : voice+dtmf Media line : 1 State : STREAM_ADDING (2) Stream address type : 1 Callid : 2131 Negotiated Codec : g711ulaw, bytes :160 Nego. Codec payload : 0 (tx), 0 (rx) Negotiated DTMF relay : rtp-nte Negotiated NTE payload : 101 (tx), 101 (rx) Negotiated CN payload : 0 Media Srce Addr/Port : [1.1.1.1]:17288 Media Dest Addr/Port : [2.2.2.3]:18914 021047: Feb 18 17:47:09.861 EST: //2131/E418CF34942C/SIP/Info/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container 021048: Feb 18 17:47:09.861 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container 021049: Feb 18 17:47:09.861 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container 021050: Feb 18 17:47:09.861 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateNewRawMsg: No Data to form The Raw Message 021051: Feb 18 17:47:09.861 EST: //2131/E418CF34942C/SIP/Info/HandleSIP1xxSessionProgress: ccsip_api_call_cut_progress returned: SIP_SUCCESS 021052: Feb 18 17:47:09.861 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeState: 0x493CF590 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE) 021053: Feb 18 17:47:09.861 EST: //2131/E418CF34942C/SIP/Info/HandleSIP1xxSessionProgress: Transaction Complete. Lock on Facilities released. 021054: Feb 18 17:47:09.865 EST: //2131/E418CF34942C/SIP/Info/ccsip_bridge: confID = 739, srcCallID = 2131, dstCallID = 2130 021055: Feb 18 17:47:09.865 EST: //2131/E418CF34942C/SIP/Info/sipSPIUupdateCcCallIds: Old src/dest ccCallids: -1/-1, new src/dest ccCallids: 2131/2130 021056: Feb 18 17:47:09.865 EST: //2131/E418CF34942C/SIP/Info/sipSPIUupdateCcCallIds: Old streamcallid=2131, new streamcallid=2131 021057: Feb 18 17:47:09.865 EST: //2131/E418CF34942C/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-TDM 021058: Feb 18 17:47:09.865 EST: //2131/E418CF34942C/SIP/Info/ccsip_bridge: xcoder_attached = 0, xmitFunc = 1087287380, ccb xmitFunc = 1087287380 021059: Feb 18 17:47:09.865 EST: //2131/E418CF34942C/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions 021060: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 2131) to the VOIP RTP library 021061: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr 021062: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 1.1.1.1 021063: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1 021064: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info laddr = 1.1.1.1, lport = 17288, raddr = 2.2.2.3, rport=18914, do_rtcp=TRUE src_callid = 2131, dest_callid = 2130, stream type = voice+dtmf, stream direction = SENDRECV media_ip_addr = 2.2.2.3, vrf tableid = 0 media_addr_type = 1 021065: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdateRtcpSession: RTP session already created - update 021066: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:4C171CF8 021067: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Info/sipSPIUpdateRtcpSession: DTMF inb/oob iwf enabled 0 021068: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Media/sipSPIGetNewLocalMediaDirection: New Remote Media Direction = SENDRECV Present Local Media Direction = SENDRECV New Local Media Direction = SENDRECV retVal = 0 021069: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeStreamState: Stream (callid = 2131) State changed from (STREAM_ADDING) to (STREAM_ACTIVE) 021070: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Info/ccsip_bridge: DTMF inb/oob iwf enabled 0 021071: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Info/ccsip_caps_ind: Entry 021072: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Info/ccsip_get_rtcp_session_parameters: CURRENT VALUES: stream_callid=2131, current_seq_num=0x1B37 021073: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Info/ccsip_get_rtcp_session_parameters: NEW VALUES: stream_callid=2131, current_seq_num=0x2226 021074: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Info/ccsip_caps_ind: Load DSP with negotiated codec: g711ulaw, Bytes=160 021075: Feb 18 17:47:09.869 EST: //2131/E418CF34942C/SIP/Info/ccsip_caps_ind: Set forking flag to 0x0 021076: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Info/sipSPISetDTMFRelayMode: Set DSP for dtmf-relay = CC_CAP_DTMF_RELAY_NTE_AND_OOB with rx payload = 101, tx payload = 101 021077: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Info/sip_set_modem_caps: Preferred (or the one that came from DSM) modem relay=0, from CLI config=0 021078: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Info/sip_set_modem_caps: Disabling Modem Relay... 021079: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Info/sip_set_modem_caps: Negotiation already Done. Set negotiated Modem caps and generate SDP Xcap list 021080: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Info/sip_set_modem_caps: Modem Relay & Passthru both disabled 021081: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Info/sip_set_modem_caps: nse payload = 0, ptru mode = 0, ptru-codec=0, redundancy=0, xid=0, relay=0, sprt-retry=12, latecncy=200, compres-dir=3, dict=1024, strnlen=32 021082: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Media/sipSPISetStreamInfo: 1 Active Streams 021083: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Media/sipSPISetStreamInfo: Adding stream type (voice+dtmf) from media line 1 021084: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Media/sipSPISetStreamInfo: caps.stream_count=1,caps.stream[0].stream_type=0x2, caps.stream_list.xmitFunc= 021085: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Media/sipSPISetStreamInfo: voip_rtp_xmit, caps.stream_list.context= 021086: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Media/sipSPISetStreamInfo: 0x4BB22F20 (gccb) 021087: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Info/ccsip_caps_ind: Load DSP with codec : g711ulaw, Bytes=160, payload = 0 021088: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Info/ccsip_caps_ind: ccsip_caps_ind: ccb->pld.flags_ipip = 0x2201 021089: Feb 18 17:47:09.873 EST: //2131/E418CF34942C/SIP/Info/ccsip_caps_ack: Set forking flag to 0x0 021090: Feb 18 17:47:11.986 EST: //2131/E418CF34942C/SIP/Info/ccsip_indicate_rt_packet_stats: Processing stats for callid=2131, proc_id=9 021091: Feb 18 17:47:15.246 EST: //2131/E418CF34942C/SIP/Info/ccsip_indicate_rt_packet_stats: Processing stats for callid=2131, proc_id=9 021092: Feb 18 17:47:17.754 EST: //2131/E418CF34942C/SIP/Info/ccsip_indicate_rt_packet_stats: Processing stats for callid=2131, proc_id=9 021093: Feb 18 17:47:20.274 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[1235] removed. 021094: Feb 18 17:47:20.274 EST: //2129/D55CC5BA9426/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table. 021095: Feb 18 17:47:20.274 EST: //2129/D55CC5BA9426/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x493BFFCC key=D8C769DE-FD4411DD-942BEE7C-DC4778E6@1.1.1.1 021096: Feb 18 17:47:20.274 EST: //2129/D55CC5BA9426/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd 021097: Feb 18 17:47:20.274 EST: //2129/D55CC5BA9426/SIP/Info/ccsip_qos_cleanup: Entry 021098: Feb 18 17:47:20.274 EST: //2129/D55CC5BA9426/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed 021099: Feb 18 17:47:20.274 EST: //2129/D55CC5BA9426/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 493BFFCC 021100: Feb 18 17:47:24.290 EST: //2131/E418CF34942C/SIP/Info/ccsip_indicate_rt_packet_stats: Processing stats for callid=2131, proc_id=9 021101: Feb 18 17:47:28.294 EST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [2.2.2.3]:5060 021102: Feb 18 17:47:28.294 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 021103: Feb 18 17:47:28.294 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x481FCF88 021104: Feb 18 17:47:28.294 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x481FCF88, addr=2.2.2.3, port=5060, connid=2, transport=UDP 021105: Feb 18 17:47:28.294 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 021106: Feb 18 17:47:28.294 EST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 OK Via: SIP/2.0/UDP 1.1.1.1:5060;branch=z9hG4bKDE81E63;received=1.1.1.1 From: "marian" ;tag=2A2FDDF4-242 To: ;tag=as675e7559 Call-ID: E776A57C-FD4411DD-9431EE7C-DC4778E6@1.1.1.1 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 212 v=0 o=root 1065 1066 IN IP4 2.2.2.3 s=session c=IN IP4 2.2.2.3 t=0 0 m=audio 18914 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - 021107: Feb 18 17:47:28.298 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container 021108: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Info/sipSPIhandle200OKInvite: Transaction active. Facilities will be queued. 021109: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Info/sipSPIhandle200OKInvite: *** This ccb is the parent 021110: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Error/sipSPIProcessNotifyCallInfoHeader: Call-Info header with for Unsolicited Notify Absent,Disabling Unsolicited Notifies 021111: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Info/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container 021112: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1 SIP: Attribute mid, level 1 instance 1 not found. 021113: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr 021114: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 1.1.1.1 021115: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoAudioNegotiation: Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1 021116: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled 021117: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1 021118: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoDTMFRelayNegotiation: RTP-NTE DTMF relay option 021119: Feb 18 17:47:28.298 EST: //2131/E418CF34942C/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events. 021120: Feb 18 17:47:28.298 EST: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0 021121: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Info/sip_do_nse_negotiation: Remote NSE payload = local one = 0, Use it 021122: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay 021123: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Info/sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0 021124: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Info/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=2.2.2.3, dest_port=18914 021125: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING) 021126: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Media/sipSPICompareStreams: stream 1 dest_port: old=18914 new=18914 021127: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Media/sipSPIGetNewLocalMediaDirection: New Remote Media Direction = SENDRECV Present Local Media Direction = SENDRECV New Local Media Direction = SENDRECV retVal = 0 021128: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Media/sipSPICompareStreams: Flags set for stream 1: RTP_CHANGE=No CAPS_CHANGE=No RSVP_ADDR_CHANGE=No RSVP_MEDIA_CHANGE=No 021129: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Media/sipSPICompareSDP: Flags set for call: NEW_MEDIA=No DSPDNLD_REQD=No IPIP_MEDIA=No 021130: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdCallWithSdpInfo: Preferred Codec : g711ulaw, bytes :160 Preferred DTMF relay : rtp-nte Preferred NTE payload : 101 Early Media : No Delayed Media : No Bridge Done : Yes New Media : No DSP DNLD Reqd : No 021131: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr 021132: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 1.1.1.1 021133: Feb 18 17:47:28.302 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdCallWithSdpInfo: Stream type : voice+dtmf Media line : 1 State : STREAM_ACTIVE (5) Stream address type : 1 Callid : 2131 Negotiated Codec : g711ulaw, bytes :160 Nego. Codec payload : 0 (tx), 0 (rx) Negotiated DTMF relay : rtp-nte Negotiated NTE payload : 101 (tx), 101 (rx) Negotiated CN payload : 0 Media Srce Addr/Port : [1.1.1.1]:17288 Media Dest Addr/Port : [2.2.2.3]:18914 021134: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Info/sipSPIProcessMediaChanges: sipSPIProcessMediaChanges 021135: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Info/sipSPIhandle200OKInvite: ccsip_api_call_connect_media returned: SIP_SUCCESS 021136: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeState: 0x493CF590 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE) 021137: Feb 18 17:47:28.306 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container 021138: Feb 18 17:47:28.306 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container 021139: Feb 18 17:47:28.306 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateNewRawMsg: No Data to form The Raw Message 021140: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Info/sipSPIShrlCall: Check peer: 100 for Shared-Line call, callid: 2131 021141: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Info/sipSPICreateAndStartRtpTimer: 021142: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Info/sipSPICreateAndStartRtpTimer: Media Inactivity Timer is disabled. 021143: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Info/sipSPIhandle200OKInvite: ccsip_api_call_connected returned: SIP_SUCCESS 021144: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer 021145: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE 021146: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Transport/sipSPITransportSendMessage: msg=0x4BEA6F48, addr=2.2.2.3, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x416E3694 021147: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 021148: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0 021149: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x4BEA6F48 021150: Feb 18 17:47:28.306 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x4BEA6F48, addr=2.2.2.3, port=5060, connId=2 for UDP 021151: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeState: 0x493CF590 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_ACTIVE, SUBSTATE_NONE) 021152: Feb 18 17:47:28.306 EST: //2131/E418CF34942C/SIP/Call/sipSPICallInfo: The Call Setup Information is: Call Control Block (CCB) : 0x493CF590 State of The Call : STATE_ACTIVE TCP Sockets Used : NO Calling Number : 9999999999 Called Number : 4444444444 Source IP Address (Sig ): 1.1.1.1 Destn SIP Req Addr:Port : 2.2.2.2:5060 Destn SIP Resp Addr:Port : 2.2.2.2:5060 Destination Name : outbound.domain.com 021153: Feb 18 17:47:28.310 EST: //2131/E418CF34942C/SIP/Call/sipSPIMediaCallInfo: Number of Media Streams: 1 Media Stream : 1 Negotiated Codec : g711ulaw Negotiated Codec Bytes : 160 Nego. Codec payload : 0 (tx), 0 (rx) Negotiated Dtmf-relay : 6 Dtmf-relay Payload : 101 (tx), 101 (rx) Source IP Address (Media): 1.1.1.1 Source IP Port (Media): 17288 Destn IP Address (Media): 2.2.2.3 Destn IP Port (Media): 18914 Orig Destn IP Address:Port (Media): [ - ]:0 021154: Feb 18 17:47:28.310 EST: //2131/E418CF34942C/SIP/Info/sipSPICallActive: Transaction Complete. Lock on Facilities released. 021155: Feb 18 17:47:28.310 EST: //2131/E418CF34942C/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd 021156: Feb 18 17:47:28.314 EST: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_process_udp_queue_event: UDP: Mismatch in send msg's target addr: 2.2.2.3, port: 5060 to those in entry's values 021157: Feb 18 17:47:28.314 EST: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_process_udp_queue_event: addr: 2.2.2.2, port: 5060 021158: Feb 18 17:47:28.322 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 53 021159: Feb 18 17:47:28.322 EST: //-1/xxxxxxxxxxxx/SIP/Error/act_active_send_msg_failure: Send Error to 2.2.2.3:5060 for transport UDP 021160: Feb 18 17:47:28.322 EST: //2131/E418CF34942C/SIP/Info/ccsip_set_cc_cause_for_spi_err: Categorized cause:38, category:186 021161: Feb 18 17:47:28.322 EST: //2131/E418CF34942C/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(127) for outgoing call 021162: Feb 18 17:47:28.322 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[2131], src[6] 021163: Feb 18 17:47:28.322 EST: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQSIG: No Inbound Container Created !!! 021164: Feb 18 17:47:28.322 EST: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQ931: No Inbound Container Created !!! 021165: Feb 18 17:47:28.322 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeState: 0x493CF590 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE) 021166: Feb 18 17:47:28.330 EST: //2131/E418CF34942C/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:4BC5AE74 021167: Feb 18 17:47:28.330 EST: //2131/E418CF34942C/SIP/Info/ccsip_call_statistics: Requesting stats for callid=2131 021168: Feb 18 17:47:28.334 EST: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT 021169: Feb 18 17:47:28.334 EST: //2131/E418CF34942C/SIP/Info/ccsip_indicate_rt_packet_stats: Processing stats for callid=2131, proc_id=1 021170: Feb 18 17:47:28.338 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7 021171: Feb 18 17:47:28.338 EST: //2131/E418CF34942C/SIP/Info/sipSPIIcpifUpdate: CallState: 4 Playout: 9500 DiscTime:70780024 ConnTime 70780020 021172: Feb 18 17:47:28.338 EST: //2131/E418CF34942C/SIP/State/sipSPIChangeState: 0x493CF590 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE) 021173: Feb 18 17:47:28.342 EST: //2131/E418CF34942C/SIP/Call/sipSPICallInfo: The Call Setup Information is: Call Control Block (CCB) : 0x493CF590 State of The Call : STATE_DEAD TCP Sockets Used : NO Calling Number : 9999999999 Called Number : 4444444444 Source IP Address (Sig ): 1.1.1.1 Destn SIP Req Addr:Port : 2.2.2.2:5060 Destn SIP Resp Addr:Port : 2.2.2.2:5060 Destination Name : outbound.domain.com 021174: Feb 18 17:47:28.342 EST: //2131/E418CF34942C/SIP/Call/sipSPIMediaCallInfo: Number of Media Streams: 1 Media Stream : 1 Negotiated Codec : g711ulaw Negotiated Codec Bytes : 160 Nego. Codec payload : 0 (tx), 0 (rx) Negotiated Dtmf-relay : 6 Dtmf-relay Payload : 101 (tx), 101 (rx) Source IP Address (Media): 1.1.1.1 Source IP Port (Media): 17288 Destn IP Address (Media): 2.2.2.3 Destn IP Port (Media): 18914 Orig Destn IP Address:Port (Media): [ - ]:0 021175: Feb 18 17:47:28.342 EST: //2131/E418CF34942C/SIP/Call/sipSPICallInfo: Disconnect Cause (CC) : 127 Disconnect Cause (SIP) : 200 021176: Feb 18 17:47:28.342 EST: //2131/E418CF34942C/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 853 021177: Feb 18 17:47:28.342 EST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[1236] removed. 021178: Feb 18 17:47:28.342 EST: //2131/E418CF34942C/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table. 021179: Feb 18 17:47:28.342 EST: //2131/E418CF34942C/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x493CF590 key=E776A57C-FD4411DD-9431EE7C-DC4778E6@1.1.1.1 021180: Feb 18 17:47:28.342 EST: //2131/E418CF34942C/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd 021181: Feb 18 17:47:28.342 EST: //2131/E418CF34942C/SIP/Info/ccsip_qos_cleanup: Entry 021182: Feb 18 17:47:28.342 EST: //2131/E418CF34942C/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed 021183: Feb 18 17:47:28.346 EST: //2131/E418CF34942C/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 493CF590 021184: Feb 18 17:47:29.294 EST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [2.2.2.3]:5060 021185: Feb 18 17:47:29.294 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 021186: Feb 18 17:47:29.294 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x481FCF88 021187: Feb 18 17:47:29.294 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x481FCF88, addr=2.2.2.3, port=5060, connid=2, transport=UDP 021188: Feb 18 17:47:29.294 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 021189: Feb 18 17:47:29.294 EST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 OK Via: SIP/2.0/UDP 1.1.1.1:5060;branch=z9hG4bKDE81E63;received=1.1.1.1 From: "marian" ;tag=2A2FDDF4-242 To: ;tag=as675e7559 Call-ID: E776A57C-FD4411DD-9431EE7C-DC4778E6@1.1.1.1 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 212 v=0 o=root 1065 1066 IN IP4 2.2.2.3 s=session c=IN IP4 2.2.2.3 t=0 0 m=audio 18914 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - 021190: Feb 18 17:47:29.294 EST: //-1/xxxxxxxxxxxx/SIP/Error/sipSPILocateInviteDialogCCB: Could not find ccb for response 021191: Feb 18 17:47:30.298 EST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [2.2.2.3]:5060 021192: Feb 18 17:47:30.298 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 021193: Feb 18 17:47:30.298 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x481FCF88 021194: Feb 18 17:47:30.298 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x481FCF88, addr=2.2.2.3, port=5060, connid=2, transport=UDP 021195: Feb 18 17:47:30.298 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 021196: Feb 18 17:47:30.298 EST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 OK Via: SIP/2.0/UDP 1.1.1.1:5060;branch=z9hG4bKDE81E63;received=1.1.1.1 From: "marian" ;tag=2A2FDDF4-242 To: ;tag=as675e7559 Call-ID: E776A57C-FD4411DD-9431EE7C-DC4778E6@1.1.1.1 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 212 v=0 o=root 1065 1066 IN IP4 2.2.2.3 s=session c=IN IP4 2.2.2.3 t=0 0 m=audio 18914 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - 021197: Feb 18 17:47:30.298 EST: //-1/xxxxxxxxxxxx/SIP/Error/sipSPILocateInviteDialogCCB: Could not find ccb for response 021198: Feb 18 17:47:32.294 EST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [2.2.2.3]:5060 021199: Feb 18 17:47:32.294 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 021200: Feb 18 17:47:32.294 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x481FCF88 021201: Feb 18 17:47:32.294 EST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x481FCF88, addr=2.2.2.3, port=5060, connid=2, transport=UDP 021202: Feb 18 17:47:32.294 EST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 021203: Feb 18 17:47:32.294 EST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 OK Via: SIP/2.0/UDP 1.1.1.1:5060;branch=z9hG4bKDE81E63;received=1.1.1.1 From: "marian" ;tag=2A2FDDF4-242 To: ;tag=as675e7559 Call-ID: E776A57C-FD4411DD-9431EE7C-DC4778E6@1.1.1.1 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 212 v=0 o=root 1065 1066 IN IP4 2.2.2.3 s=session c=IN IP4 2.2.2.3 t=0 0 m=audio 18914 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - 021204: Feb 18 17:47:32.294 EST: //-1/xxxxxxxxxxxx/SIP/Error/sipSPILocateInviteDialogCCB: Could not find ccb for response Router# Router# Router# Router#