Buy or Renew
Buy or Renew
Cisco + Splunk: It’s a new day for your data. Learn more.
 
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
cancel
Start a conversation
2920
Views
0
Helpful
1
Replies

Skype for Business conference call drops dial-in users on entry to the meeting through Cisco 2621XM

kristiyan_n
Level 1
Level 1

‎09-29-2016 01:02 AM

Hello guys, 

I have the following problem with my S4B conferences:

When dial-in users call my dial-in number they connect to the conferencing gateway and enter their ID, and as soon they join the meeting after stating their name, their call gets dropped. As soon as the "You are now joining the meeting" finishes, they get dropped.

Callers go through a Cisco 2621XM to connect to the meeting.

If a person in another Lync organization calls me the call does not get dropped and we can talk for hours. The issue only comes up when the caller joins a meeting I've organized. 

I've already turned Media Bypass on and set EnableSessionTimer = True, RTCPActiveCalls = False, RTCPCallsOnHold = False

For testing purposes I set up Asterisk so the calls can go through it instead of the Cisco ISR and there was no problem. 

The conferencing problem seems to be with the Cisco trunk. 

Does anyone have a Lync integration with a Cisco IRS and has he/she encountered this problem? Thanks

The dial-peer on the Cisco router is the following:

dial-peer voice 7 voip
description Lync
preference 1
destination-pattern +359249106..
voice-class codec 1
session protocol sipv2
session target ipv4:192.168.1.1
session transport tcp
dtmf-relay h245-signal h245-alphanumeric

There is a terminal monitor log of a call to the conferencing bridge on the Lync server:

Mar 1 20:10:53.446: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_sockerror_to_spi: Sending TCP Remote Closure to SPI, connid: 3
Mar 1 20:10:53.446: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWSocketException: context=0x00000000
Mar 1 20:10:53.446: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessSocketExceptions: gConnTab=0x85196EBC, addr=172.16.35.16, port=49737, connid=3, transport=tcp
Mar 1 20:10:53.446: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostCloseConnection: Posting TCP conn close for addr=172.16.35.16, port=49737, connid=3
Mar 1 20:10:53.446: //-1/xxxxxxxxxxxx/SIP/Transport/sipDeleteConnInstance: Deleted conn=0x84F61574, connid=3, addr=172.16.35.16, port=49737, transport=tcp
Mar 1 20:10:53.454: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_purge_entry: Socket fd: 3 closed for connid 3 with address: 172.16.35.16, remote port: 49737
Mar 1 20:10:53.458: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
Mar 1 20:10:53.458: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.35.16, port=49747, connid=3, transport=tcp
Mar 1 20:10:53.458: //-1/xxxxxxxxxxxx/SIP/Transport/sipCreateConnInstance: Created new accptd conn=0x85197920, connid=3, addr=172.16.35.16, port=49747, transport=tcp
Mar 1 20:10:53.622: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
Mar 1 20:10:53.622: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.35.16, port=49747, connid=3, transport=tcp
Mar 1 20:10:53.622: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:cisco.kris.local SIP/2.0
FROM: <sip:skype.kris.local:5060;transport=Tcp;ms-opaque=073b03d74bdbcc11>;epid=57E1FE531C;tag=455c88ad58
TO: <sip:cisco.kris.local>
CSEQ: 1135 OPTIONS
CALL-ID: 78a892bbcdee4bbf8cfaa5fcc4809060
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 172.16.35.16:49747;branch=z9hG4bKbaa3fca8
CONTACT: <sip:skype.kris.local:5060;transport=Tcp;maddr=172.16.35.16>
CONTENT-LENGTH: 0
USER-AGENT: RTCC/6.0.0.0 MediationServer

Mar 1 20:10:53.626: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 172.16.35.16,Port 49747, Transport 2, SentBy Port 49747
Mar 1 20:10:53.626: //-1/448C6EC8894F/SIP/State/sipSPIChangeState: 0x856C0EBC : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Mar 1 20:10:53.626: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.35.16,Port 49747, Transport 2, SentBy Port 5060
Mar 1 20:10:53.626: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
Mar 1 20:10:53.630: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.35.16,Port 49747, Transport 2, SentBy Port 49747
Mar 1 20:10:53.634: //-1/448C6EC8894F/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
Mar 1 20:10:53.634: //-1/448C6EC8894F/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
Mar 1 20:10:53.634: //-1/448C6EC8894F/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
Mar 1 20:10:53.634: //-1/448C6EC8894F/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
Mar 1 20:10:53.634: //-1/448C6EC8894F/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
Mar 1 20:10:53.634: //-1/448C6EC8894F/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
Mar 1 20:10:53.638: //-1/448C6EC8894F/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
Mar 1 20:10:53.638: //-1/448C6EC8894F/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
Mar 1 20:10:53.638: //-1/448C6EC8894F/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.16.0.241
Mar 1 20:10:53.642: //-1/448C6EC8894F/SIP/Transport/sipSPISendOptionsResponse: Sending OPTIONS Response to the transport layer
Mar 1 20:10:53.646: //-1/448C6EC8894F/SIP/Transport/sipSPITransportSendMessage: msg=0x85827908, addr=172.16.35.16, port=49747, sentBy_port=49747, is_req=0, transport=2, switch=0, callBack=0x80F34F14
Mar 1 20:10:53.646: //-1/448C6EC8894F/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:10:53.646: //-1/448C6EC8894F/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:10:53.646: //-1/448C6EC8894F/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x85827908 to default port=49747
Mar 1 20:10:53.646: //-1/448C6EC8894F/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x85827908
Mar 1 20:10:53.646: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x85827908, addr=172.16.35.16, port=49747, connId=3 for TCP
Mar 1 20:10:53.650: //-1/448C6EC8894F/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Mar 1 20:10:53.650: //-1/448C6EC8894F/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 856C0EBC
Mar 1 20:10:53.654: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_sendmsg: Socket blocked data queued
Mar 1 20:10:53.654: //-1/xxxxxxxxxxxx/SIP/Info/sipTcpQueueSendData: Data queued length: 238
Mar 1 20:10:53.662: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:10:53.670: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:10:53.678: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:10:53.686: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:10:53.694: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:10:53.702: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:10:53.710: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.35.16:49747;branch=z9hG4bKbaa3fca8
From: <sip:skype.kris.local:5060;transport=Tcp;ms-opaque=073b03d74bdbcc11>;epid=57E1FE531C;tag=455c88ad58
To: <sip:cisco.kris.local>;tag=4549B44-1912
Date: Fri, 01 Mar 2002 20:10:53 GMT
Call-ID: 78a892bbcdee4bbf8cfaa5fcc4809060
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1135 OPTIONS
Supported: 100rel,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Accept: application/sdp
Allow-Events: telephone-event
Content-Length: 167
Content-Type: application/sdp

v=0
o=CiscoSystemsSIP-GW-UserAgent 1400 2770 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 172.16.0.241

Mar 1 20:10:54.418: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
Mar 1 20:10:54.418: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.35.16, port=49750, connid=4, transport=tcp
Mar 1 20:10:54.422: //-1/xxxxxxxxxxxx/SIP/Transport/sipCreateConnInstance: Created new accptd conn=0x851979C4, connid=4, addr=172.16.35.16, port=49750, transport=tcp
Mar 1 20:10:54.426: //-1/xxxxxxxxxxxx/SIP/Info/httpish_msg_process_network_msg: HEADER LINE READ FAILURE DUE TO RS->EOF
Mar 1 20:10:54.426: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_network_message: process_network_msg: not complete
Mar 1 20:10:54.426: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_newmsg_to_spi: process_network_msg: not complete
Mar 1 20:10:54.726: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
Mar 1 20:10:54.726: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.35.16, port=49750, connid=4, transport=tcp
Mar 1 20:10:54.730: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:+35924910610@cisco.kris.local;user=phone SIP/2.0
FROM: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
TO: <sip:+35924910610@cisco.kris.local;user=phone>
CSEQ: 1136 INVITE
CALL-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 172.16.35.16:49750;branch=z9hG4bKf736f4f
CONTACT: <sip:skype.kris.local:5060;transport=Tcp;maddr=172.16.35.16;ms-opaque=073b03d74bdbcc11>
CONTENT-LENGTH: 339
SUPPORTED: timer
SUPPORTED: 100rel
USER-AGENT: RTCC/6.0.0.0 MediationServer
CONTENT-TYPE: application/sdp
ALLOW: ACK
Session-Expires: 1800
Min-SE: 90
Allow: CANCEL,BYE,INVITE,PRACK,UPDATE

v=0
o=- 20 1 IN IP4 172.16.35.16
s=session
c=IN IP4 172.16.35.16
b=CT:1000
t=0 0
m=audio 54990 RTP/AVP 97 101 13 0 8
c=IN IP4 172.16.35.16
a=rtcp:54991
a=label:Audio
a=sendrecv
a=rtpmap:97 RED/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=ptime:20

Mar 1 20:10:54.734: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 172.16.35.16,Port 49750, Transport 2, SentBy Port 49750
Mar 1 20:10:54.734: //-1/45357FE18950/SIP/State/sipSPIChangeState: 0x856B760C : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Mar 1 20:10:54.734: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 172.16.35.16,Port 49750, Transport 2, SentBy Port 5060
Mar 1 20:10:54.738: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
Mar 1 20:10:54.738: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 172.16.35.16,Port 49750, Transport 2, SentBy Port 49750
Mar 1 20:10:54.742: //-1/45357FE18950/SIP/Info/sipSPIUASSessionTimer: Min-SE Header: 90
Mar 1 20:10:54.742: //-1/45357FE18950/SIP/Info/sipSPIUASSessionTimer: SE Value: 1800
SIP: Warning: Unrecognized attribute (label)
Mar 1 20:10:54.746: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetGtdBody: No valid GTD body found.
Mar 1 20:10:54.746: //-1/45357FE18950/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
Mar 1 20:10:54.750: //-1/45357FE18950/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x856B760C key=3e76def5-9e7e-4ef1-965c-3ca2c9f44475+35924910610
Mar 1 20:10:54.750: //-1/45357FE18950/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on carrier id
Mar 1 20:10:54.750: //-1/45357FE18950/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: +35924910610
Mar 1 20:10:54.750: //-1/45357FE18950/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on destination pattern: 35924910702
Mar 1 20:10:54.750: //-1/45357FE18950/SIP/Info/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name kris test, number 35924910702, Calling oct3 0x00, oct_3a 0x80, Called number +35924910610
Mar 1 20:10:54.754: //-1/45357FE18950/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
Mar 1 20:10:54.754: //-1/45357FE18950/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
Mar 1 20:10:54.754: //-1/45357FE18950/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
Mar 1 20:10:54.758: //-1/45357FE18950/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
Mar 1 20:10:54.758: //-1/45357FE18950/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
Mar 1 20:10:54.758: //-1/45357FE18950/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
Mar 1 20:10:54.758: //-1/45357FE18950/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
Mar 1 20:10:54.758: //-1/45357FE18950/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
Mar 1 20:10:54.758: //-1/45357FE18950/SIP/Info/sipSPIContinueNewMsgInvite: Calling name kris test, number 35924910702, Calling oct3 0x00, oct_3a 0x80, ext_priv 0x00, Called number +35924910610, oct3 0x00
Mar 1 20:10:54.758: //-1/45357FE18950/SIP/Info/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE
Mar 1 20:10:54.762: //-1/45357FE18950/SIP/Info/sipSPIContinueNewMsgInvite: Requires reliable-provisional support
Mar 1 20:10:54.762: //-1/45357FE18950/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
Mar 1 20:10:54.762: //42/45357FE18950/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
Mar 1 20:10:54.762: //-1/xxxxxxxxxxxx/SIP/Info/rtpAvpCodec_to_voipCodec: Dynamic Payload :97 in SDP Body
Mar 1 20:10:54.766: //-1/xxxxxxxxxxxx/SIP/Info/rtpAvpCodec_to_voipCodec: Dynamic Payload :101 in SDP Body
Mar 1 20:10:54.766: //42/45357FE18950/SIP/Info/sipSPIDoAudioNegotiation: Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1
Mar 1 20:10:54.766: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g711ulaw ptime :20, codecbytes: 160
Mar 1 20:10:54.766: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Mar 1 20:10:54.766: //42/45357FE18950/SIP/Media/sipSPIDoPtimeNegotiation: Offered ptime:20, Negotiated ptime:20 Negotiated codec bytes: 160 for codec g711ulaw
Mar 1 20:10:54.766: //42/45357FE18950/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
Mar 1 20:10:54.766: //42/45357FE18950/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved.
Mar 1 20:10:54.766: //42/45357FE18950/SIP/Info/sipSPIDoDTMFRelayNegotiation: Reserved the new NTE payload type 101
Mar 1 20:10:54.770: //42/45357FE18950/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events.
Mar 1 20:10:54.770: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0
Mar 1 20:10:54.770: //42/45357FE18950/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
Mar 1 20:10:54.770: //42/45357FE18950/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
Mar 1 20:10:54.770: //42/45357FE18950/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=172.16.35.16, dest_port=54990
Mar 1 20:10:54.770: //42/45357FE18950/SIP/Media/sipSPIUpdCallWithSdpInfo:
Preferred Codec : g729r8, bytes :20
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

Mar 1 20:10:54.774: //42/45357FE18950/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.16.0.241
Mar 1 20:10:54.774: //42/45357FE18950/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 42 peer 0 flags 0x201
Mar 1 20:10:54.774: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 42, sdp 0x8585FBDC channels 0x856B91BC
Mar 1 20:10:54.774: //42/45357FE18950/SIP/Info/copy_channels:
callId 42 size 0 ptr 0x85401354)
Mar 1 20:10:54.774: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 97 mline 1
Mar 1 20:10:54.774: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 101 mline 1
Mar 1 20:10:54.774: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 13 mline 1
Mar 1 20:10:54.774: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec No Codec
Mar 1 20:10:54.778: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 0 mline 1
Mar 1 20:10:54.778: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711ulaw
Mar 1 20:10:54.778: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Mar 1 20:10:54.778: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation done: stream->negotiated_ptime=20,stream->negotiated_codec_bytes=160, coverted ptime=20 stream->mline_index=1, media_ndx=1
Mar 1 20:10:54.778: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 5 ptype 0 time 20, bytes 160 as channel 0 mline 1 ss 0 172.16.35.16:54990
Mar 1 20:10:54.778: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 8 mline 1
Mar 1 20:10:54.778: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711alaw
Mar 1 20:10:54.778: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=20, media_ndx=1
Mar 1 20:10:54.782: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g711alaw ptime :20, codecbytes: 160
Mar 1 20:10:54.782: //42/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 6 ptype 8 time 20, bytes 160 as channel 1 mline 1 ss 0 172.16.35.16:54990
Mar 1 20:10:54.782: //42/45357FE18950/SIP/Info/sipSPI_ipip_report_media_to_peer:
Report initial call media
Mar 1 20:10:54.782: //42/45357FE18950/SIP/Info/copy_channels:
callId 42 size 152 ptr 0x845BEFE4)
Mar 1 20:10:54.782: //42/45357FE18950/SIP/Info/sipSPI_ipip_report_media_to_peer:
CCSIP: Unable to report channel ind
Mar 1 20:10:54.782: //42/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Call Entry Is Not Found
Mar 1 20:10:54.782: //42/45357FE18950/SIP/Media/sipSPIUpdCallWithSdpInfo:
Stream type : voice+dtmf
Media line : 1
State : STREAM_ADDING (2)
Callid : -1
Negotiated Codec : g711ulaw, bytes :160
Negotiated DTMF relay : rtp-nte
Negotiated NTE payload : 101
Negotiated CN payload : 13
Media Srce Addr/Port : 172.16.0.241:0
Media Dest Addr/Port : 172.16.35.16:54990

Mar 1 20:10:54.786: //42/45357FE18950/SIP/Info/sipSPIHandleInviteMedia:
Negotiated Codec : g711ulaw, bytes :160
Preferred Codec : g729r8, bytes :20
Preferred DTMF relay 1 : 6
Preferred DTMF relay 2 : 0
Negotiated DTMF relay : 6
Preferred and Negotiated NTE payloads: 101 101
Preferred and Negotiated NSE payloads: 100 0
Preferred and Negotiated Modem Relay: 0 0
Preferred and Negotiated Modem Relay GwXid: 1 0

Mar 1 20:10:54.786: //42/45357FE18950/SIP/Info/sipSPIDoQoSNegotiation: SDP body with media description
Mar 1 20:10:54.786: //42/45357FE18950/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
Mar 1 20:10:54.786: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 16732 for stream 1
Mar 1 20:10:54.790: //42/45357FE18950/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Reserving rtp port for stream 1, src_port=16732
Mar 1 20:10:54.790: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
Mar 1 20:10:54.790: //42/45357FE18950/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Setting stream 1 portnum to 16732
Mar 1 20:10:54.790: //42/45357FE18950/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Negotiated method of dtmf relayand pyld: 6 101

Mar 1 20:10:54.790: //42/45357FE18950/SIP/Info/sipSPIAddBillingInfoToCcb: sipCallId for billing records = 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
Mar 1 20:10:54.794: //42/45357FE18950/SIP/Info/sipSPI_ipip_store_channel_info: Store channelInfo in CallInfo
Mar 1 20:10:54.794: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD passed.
Mar 1 20:10:54.794: //-1/45357FE18950/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=35924910702
----- ccCallInfo IE subfields -----
cisco-ani=35924910702
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=+35924910610
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=-1
Mar 1 20:10:54.798: //-1/45357FE18950/CCAPI/cc_api_call_setup_ind_common:
Interface=0x84F61A50, Call Info(
Calling Number=35924910702(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=+35924910610(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=0, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=42
Mar 1 20:10:54.798: //42/45357FE18950/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=35924910702(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=+35924910610(TON=Unknown, NPI=Unknown))
Mar 1 20:10:54.798: //42/45357FE18950/SIP/Info/sipSPIContinueNewMsgInvite: ccsip_api_call_setup_ind returned: SIP_SUCCESS
Mar 1 20:10:54.802: //42/45357FE18950/SIP/Info/sipSPIUaddCcbToUASRespTable: ****Adding to UAS Response table.
Mar 1 20:10:54.802: //42/45357FE18950/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x856B760C key=3e76def5-9e7e-4ef1-965c-3ca2c9f444754549F98-14C8
Mar 1 20:10:54.802: //42/45357FE18950/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 2A to table
Mar 1 20:10:54.806: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x85827BD8, addr=172.16.35.16, port=49750, sentBy_port=49750, is_req=0, transport=2, switch=0, callBack=0x00000000
Mar 1 20:10:54.806: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:10:54.806: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:10:54.806: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x85827BD8 to default port=49750
Mar 1 20:10:54.806: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x85827BD8
Mar 1 20:10:54.806: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x85827BD8, addr=172.16.35.16, port=49750, connId=4 for TCP
Mar 1 20:10:54.810: //42/45357FE18950/SIP/State/sipSPIChangeState: 0x856B760C : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_RECD_INVITE, SUBSTATE_NONE)
Mar 1 20:10:54.810: //42/45357FE18950/SIP/Info/sipSPIProcessContactInfo: Previous Hop 172.16.35.16:5060
Mar 1 20:10:54.810: //42/45357FE18950/CCAPI/cc_process_call_setup_ind:
Event=0x85565BF8
Mar 1 20:10:54.818: //42/45357FE18950/CCAPI/ccCallSetContext:
Context=0x84087F08
Mar 1 20:10:54.822: //42/45357FE18950/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 42 with tag 0 to app "_ManagedAppProcess_Default"
Mar 1 20:10:54.822: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 172.16.35.16:49750;branch=z9hG4bKf736f4f
From: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
To: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
Date: Fri, 01 Mar 2002 20:10:54 GMT
Call-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1136 INVITE
Allow-Events: telephone-event
Content-Length: 0

Mar 1 20:10:54.826: //42/45357FE18950/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
Mar 1 20:10:54.830: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
Mar 1 20:10:54.834: //42/45357FE18950/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=7, Params=0x84088B98, Progress Indication=NULL(0)
Mar 1 20:10:54.834: //42/45357FE18950/CCAPI/ccCheckClipClir:
In: Calling Number=35924910702(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Mar 1 20:10:54.834: //42/45357FE18950/CCAPI/ccCheckClipClir:
Out: Calling Number=35924910702(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Mar 1 20:10:54.834: //42/45357FE18950/CCAPI/ccCallSetupRequest:
Destination Pattern=+359249106.., Called Number=+35924910610, Digit Strip=FALSE
Mar 1 20:10:54.838: //42/45357FE18950/CCAPI/ccCallSetupRequest:
Calling Number=35924910702(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=+35924910610(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=kris test
Account Number=35924910702, Final Destination Flag=TRUE,
Guid=45357FE1-2C87-11D6-8950-D3723AB19C5F, Outgoing Dial-peer=7
Mar 1 20:10:54.838: //42/45357FE18950/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=35924910702
----- ccCallInfo IE subfields -----
cisco-ani=35924910702
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=+35924910610
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=-1
Mar 1 20:10:54.842: //42/45357FE18950/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x84F61A50, Interface Type=3, Destination=, Mode=0x0,
Call Params(Calling Number=35924910702(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=+35924910610(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=7, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
Mar 1 20:10:54.842: //43/000000000000/SIP/State/sipSPIChangeState: 0x856CA76C : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Mar 1 20:10:54.842: //43/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 0, codec 5 channel 54990, ip AC102310:54990 params 0x85838128 caps 0x845FD61C
Mar 1 20:10:54.846: //43/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 1, codec 6 channel 54990, ip AC102310:54990 params 0x85838128 caps 0x845FD61C
Mar 1 20:10:54.846: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
Mar 1 20:10:54.846: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
Mar 1 20:10:54.846: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 138)
Mar 1 20:10:54.846: //42/45357FE18950/SIP/Info/ccsip_event_handler:
ccsip_event_handler: peer ID 43 chans 0x840B96E0 event 138 flags 0x1202003C 0x601 data 0x840B96E0
Mar 1 20:10:54.846: //42/45357FE18950/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_SET_MODE: peer ID 43 chans 0x840B96E0 event 138 flags 0x1202003C 0x601 data 0x840B96E0
Mar 1 20:10:54.850: //42/45357FE18950/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_SET_MODE: peer ID 43 chans 0x840B96E0 event 138 flags 0x1202003C 0x601 data 0x840B96E0, type = 3
Mar 1 20:10:54.850: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
Mar 1 20:10:54.850: //43/45357FE18950/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
Mar 1 20:10:54.850: //43/45357FE18950/CCAPI/ccCallSetContext:
Context=0x84088B48
Mar 1 20:10:54.850: //42/45357FE18950/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=7
Mar 1 20:10:54.850: //43/000000000000/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 2B to table
Mar 1 20:10:54.854: //43/000000000000/SIP/Info/act_idle_continue_call_setup:
Mar 1 20:10:54.854: //43/000000000000/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0
Mar 1 20:10:54.854: //43/000000000000/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
Mar 1 20:10:54.854: //43/000000000000/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1
Mar 1 20:10:54.854: //43/000000000000/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
callid 43, channels 0x840A666C caps 0x845FD61C
Mar 1 20:10:54.854: //43/000000000000/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
pref dtmf 101
Mar 1 20:10:54.858: //43/000000000000/SIP/Info/sipSPIValidateGtd: No rawMsg from CCAPI
Mar 1 20:10:54.858: //43/000000000000/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
Mar 1 20:10:54.858: //43/000000000000/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.
Mar 1 20:10:54.858: //43/000000000000/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x856CA76C key=45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
Mar 1 20:10:54.858: //43/000000000000/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
Mar 1 20:10:54.862: //43/45357FE18950/CCAPI/cc_api_call_proceeding:
Interface=0x84F61A50, Progress Indication=NULL(0)
Mar 1 20:10:54.862: //43/000000000000/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.16.0.241
Mar 1 20:10:54.862: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 16502 for stream 1
Mar 1 20:10:54.862: //43/000000000000/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101
Mar 1 20:10:54.866: //43/000000000000/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
Mar 1 20:10:54.866: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 172.16.1.218,Port 5060, Transport 2, SentBy Port 5060
Mar 1 20:10:54.866: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
Mar 1 20:10:54.866: //43/000000000000/SIP/Event/sipSPICreateRpid: Received Octet3A=0x80 -> Setting ;screen=no ;privacy=off
Mar 1 20:10:54.874: //43/000000000000/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
Mar 1 20:10:54.874: //43/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Mar 1 20:10:54.874: //43/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x85827CF8, addr=172.16.1.218, port=5060, sentBy_port=0, is_req=1, transport=2, switch=0, callBack=0x80F320C8
Mar 1 20:10:54.874: //43/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:10:54.878: //43/000000000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:10:54.878: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostRequestConnection: Posting TCP conn create request for addr=172.16.1.218, port=5060, context=0x85196EBC
Mar 1 20:10:54.878: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetConnWaitTimer: Wait timer set for connection=0x85197BB0,addr=172.16.1.218, port=5060
Mar 1 20:10:54.878: //-1/xxxxxxxxxxxx/SIP/Transport/sipCreateConnInstance: Created new initiated conn=0x85197BB0, connid=-1, addr=172.16.1.218, port=5060, transport=tcp
Mar 1 20:10:54.878: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering ccb=0x856CA76C with connection=0x85197BB0
Mar 1 20:10:54.878: //43/000000000000/SIP/Transport/sipSPITransportSendMessage: Deferred sending msg=0x85827CF8
Mar 1 20:10:54.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWConnectionCreated: context=0x85196EBC
Mar 1 20:10:54.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessConnCreated: gConnTab=0x85196EBC, addr=172.16.1.218, port=5060, connid=6, transport=tcp
Mar 1 20:10:54.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnectionCreated: Moving connection=0x85197BB0, connid=6state to pending
Mar 1 20:10:54.890: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWConnectionCreated: context=0x85196EBC
Mar 1 20:10:54.890: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessConnCreated: gConnTab=0x85196EBC, addr=172.16.1.218, port=5060, connid=6, transport=tcp
Mar 1 20:10:54.890: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportStopConnWaitTimer: Wait timer stopped for connection=0x85197BB0,addr=172.16.1.218, port=5060
Mar 1 20:10:54.890: //43/000000000000/SIP/Transport/sipTransportPostInternalMsg: Posting Internal Msg type=0
Mar 1 20:10:54.890: //43/000000000000/SIP/Info/ccsip_lock_ccb: CCB locked; reference count=1
Mar 1 20:10:54.890: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessInternalMsg: context=0x856CA76C
Mar 1 20:10:54.894: //43/000000000000/SIP/Info/ccsip_unlock_ccb: CCB unlocked; reference count=0
Mar 1 20:10:54.894: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x85827CF8, addr=172.16.1.218, port=5060, connId=6 for TCP
Mar 1 20:10:54.894: //43/000000000000/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE
Mar 1 20:10:54.894: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued.
Mar 1 20:10:54.894: //43/000000000000/SIP/State/sipSPIChangeState: 0x856CA76C : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE)
Mar 1 20:10:54.894: //43/000000000000/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions
Mar 1 20:10:54.898: //43/000000000000/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 43) to the VOIP RTP library
Mar 1 20:10:54.898: //43/000000000000/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.16.0.241
Mar 1 20:10:54.898: //43/000000000000/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1
Mar 1 20:10:54.898: //43/000000000000/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info
laddr = 172.16.0.241, lport = 16502, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE
src_callid = 43, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY
media_ip_addr = 0.0.0.0
Mar 1 20:10:54.898: //43/000000000000/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one
Mar 1 20:10:54.902: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_sendmsg: Socket blocked data queued
Mar 1 20:10:54.902: //-1/xxxxxxxxxxxx/SIP/Info/sipTcpQueueSendData: Data queued length: 614
Mar 1 20:10:55.090: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:10:55.090: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:10:55.618: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:+35924910610@172.16.1.218:5060 SIP/2.0
Via: SIP/2.0/TCP 172.16.0.241;branch=z9hG4bK5DD84
From: "kris test" <sip:35924910702@172.16.0.241>;tag=454A018-AD3
To: <sip:+35924910610@172.16.1.218>
Date: Fri, 01 Mar 2002 20:10:54 GMT
Call-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
Supported: 100rel,timer,replaces
Min-SE: 1800
Cisco-Guid: 1161134049-747049430-2303775602-984718431
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Remote-Party-ID: "kris test" <sip:35924910702@172.16.0.241>;party=calling;screen=no;privacy=off
Timestamp: 1015013454
Contact: <sip:35924910702@172.16.0.241:5060;transport=tcp>
Expires: 1800
Allow-Events: telephone-event
Content-Type: application/sdp
Content-Length: 283

v=0
o=CiscoSystemsSIP-GW-UserAgent 1360 9685 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 16502 RTP/AVP 0 8 101 19
c=IN IP4 172.16.0.241
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:19 CN/8000

Mar 1 20:10:55.818: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x85196EBC
Mar 1 20:10:55.822: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.1.218, port=5060, connid=6, transport=tcp
Mar 1 20:10:55.822: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
FROM: "kris test"<sip:35924910702@172.16.0.241>;tag=454A018-AD3
TO: <sip:+35924910610@172.16.1.218>
CSEQ: 101 INVITE
CALL-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
VIA: SIP/2.0/TCP 172.16.0.241;branch=z9hG4bK5DD84
CONTENT-LENGTH: 0
TIMESTAMP: 1015013454

Mar 1 20:10:55.826: //43/000000000000/SIP/State/sipSPIChangeState: 0x856CA76C : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
Mar 1 20:10:56.574: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x85196EBC
Mar 1 20:10:56.578: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.1.218, port=5060, connid=6, transport=tcp
Mar 1 20:10:56.578: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
FROM: "kris test"<sip:35924910702@172.16.0.241>;tag=454A018-AD3
TO: <sip:+35924910610@172.16.1.218>;tag=80ab175cce;epid=8379FDC2F7
CSEQ: 101 INVITE
CALL-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
VIA: SIP/2.0/TCP 172.16.0.241;branch=z9hG4bK5DD84
CONTACT: <sip:win2012-04.valio.local:5060;transport=Tcp;maddr=172.16.1.218>
CONTENT-LENGTH: 254
CONTENT-TYPE: application/sdp
ALLOW: CANCEL
ALLOW: BYE
ALLOW: UPDATE
ALLOW: PRACK
REQUIRE: 100rel
SERVER: RTCC/6.0.0.0 MediationServer
Rseq: 1

v=0
o=- 86 1 IN IP4 172.16.1.218
s=session
c=IN IP4 172.16.1.218
b=CT:1000
t=0 0
m=audio 55006 RTP/AVP 0 101
c=IN IP4 172.16.1.218
a=rtcp:55007
a=label:Audio
a=sendrecv
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

SIP: Warning: Unrecognized attribute (label)
Mar 1 20:10:56.586: //43/000000000000/SIP/Info/HandleSIP1xxSessionProgress: Content-Disposition NOT received in 18x response - using default Content-Disposition values
Mar 1 20:10:56.586: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetGtdBody: No valid GTD body found.
SIP: Warning: Unrecognized attribute (label)
Mar 1 20:10:56.590: //43/000000000000/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
Mar 1 20:10:56.590: //43/000000000000/SIP/Info/sipSPIDoAudioNegotiation: Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1
Mar 1 20:10:56.590: //43/000000000000/SIP/Info/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled
Mar 1 20:10:56.590: //43/000000000000/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
Mar 1 20:10:56.590: //43/000000000000/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(101) could not be reserved.
Mar 1 20:10:56.590: //43/000000000000/SIP/Info/sipSPIDoDTMFRelayNegotiation: Payload type (101) is reserved for requested dtmf relay mode.
Mar 1 20:10:56.594: //43/000000000000/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events.
Mar 1 20:10:56.594: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0
Mar 1 20:10:56.594: //43/000000000000/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
Mar 1 20:10:56.594: //43/000000000000/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
Mar 1 20:10:56.594: //43/000000000000/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=172.16.1.218, dest_port=55006
Mar 1 20:10:56.594: //43/000000000000/SIP/Info/sipSPIDoQoSNegotiation: SDP body with media description
Mar 1 20:10:56.598: //43/000000000000/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

Mar 1 20:10:56.598: //43/000000000000/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.16.0.241
Mar 1 20:10:56.598: //43/000000000000/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 43 peer 42 flags 0x7
Mar 1 20:10:56.598: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 43, sdp 0x85433750 channels 0x856CC31C
Mar 1 20:10:56.598: //43/000000000000/SIP/Info/copy_channels:
callId 43 size 152 ptr 0x853AE940)
SIP: Attribute ptime, level 1 instance 1 not found.
Mar 1 20:10:56.598: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 0 mline 1
Mar 1 20:10:56.602: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711ulaw
Mar 1 20:10:56.602: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Mar 1 20:10:56.602: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation done: stream->negotiated_ptime=0,stream->negotiated_codec_bytes=160, coverted ptime=20 stream->mline_index=1, media_ndx=1
Mar 1 20:10:56.602: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 5 ptype 0 time 20, bytes 160 as channel 0 mline 1 ss 0 172.16.1.218:55006
Mar 1 20:10:56.602: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 101 mline 1
Mar 1 20:10:56.602: //43/000000000000/SIP/Info/sipSPI_ipip_report_media_to_peer:
Report initial call media
Mar 1 20:10:56.602: //43/000000000000/SIP/Info/copy_channels:
callId 43 size 80 ptr 0x84E385B0)
Mar 1 20:10:56.606: //43/45357FE18950/CCAPI/cc_api_event_indication:
Event=132, Call Id=43
Mar 1 20:10:56.606: //43/45357FE18950/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
Mar 1 20:10:56.606: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
Mar 1 20:10:56.606: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 132)
Mar 1 20:10:56.606: //42/45357FE18950/SIP/Info/ccsip_event_handler:
ccsip_event_handler: peer ID 43 chans 0x84E385B0 event 132 flags 0x1202003C 0x403 data 0x84E385B0
Mar 1 20:10:56.606: //42/45357FE18950/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_OPEN_CHANNEL_IND: peer ID 43 chans 0x84E385B0 event 132 flags 0x1202003C 0x403 data 0x84E385B0
Mar 1 20:10:56.606: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_NEW_MEDIA
Mar 1 20:10:56.610: //42/45357FE18950/SIP/Info/ccsip_event_handler:
ccsip_event_handler: set event->type = SIPSPI_EV_CC_NEW_MEDIA!: peer ID 43 chans 0x84E385B0 event 132 flags 0x1202003C 0x403 data 0x84E385B0
Mar 1 20:10:56.610: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
Mar 1 20:10:56.610: //43/45357FE18950/CCAPI/cc_api_caps_ind:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=43,
Caps(Codec=0x0, Fax Rate=0x252, Vad=0x855B7208,
Modem=0x3, Codec Bytes=878408476, Signal Type=878408344)
Mar 1 20:10:56.610: //43/45357FE18950/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=0, Playout Initial=0(ms), Playout Min=32767(ms),
Playout Max=65533(ms), Fax Nom=34139(ms))
Mar 1 20:10:56.610: //42/45357FE18950/SIP/Info/ccsip_get_rtcp_session_parameters: CURRENT VALUES: stream_callid=-1, current_seq_num=0x1730
Mar 1 20:10:56.610: //42/45357FE18950/SIP/Info/ccsip_get_rtcp_session_parameters: NEW VALUES: stream_callid=-1, current_seq_num=0x0
Mar 1 20:10:56.614: //42/45357FE18950/SIP/Info/ccsip_caps_ind: Load DSP with negotiated codec: g711ulaw, Bytes=160
Mar 1 20:10:56.614: //42/45357FE18950/SIP/Info/ccsip_caps_ind: Set forking flag to 0x3839
Mar 1 20:10:56.614: //42/45357FE18950/SIP/Info/sipSPISetDTMFRelayMode: Set DSP for dtmf-relay = CC_CAP_DTMF_RELAY_NTE_AND_OOB
Mar 1 20:10:56.614: //42/45357FE18950/SIP/Info/sip_set_modem_caps: Preferred (or the one that came from DSM) modem relay=0, from CLI config=0
Mar 1 20:10:56.614: //42/45357FE18950/SIP/Info/sip_set_modem_caps: Disabling Modem Relay...
Mar 1 20:10:56.614: //42/45357FE18950/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
Mar 1 20:10:56.614: //42/45357FE18950/SIP/Info/sip_set_modem_caps: Negotiation already Done. Set negotiated Modem caps and generate SDP Xcap list
Mar 1 20:10:56.614: //42/45357FE18950/SIP/Info/sip_set_modem_caps: Modem Relay & Passthru both disabled
Mar 1 20:10:56.614: //42/45357FE18950/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
Mar 1 20:10:56.618: //42/45357FE18950/SIP/Media/sipSPISetStreamInfo: 0 Active Streams
Mar 1 20:10:56.618: //42/45357FE18950/SIP/Media/sipSPISetStreamInfo: Number of active streams is zero (0)!
Mar 1 20:10:56.618: //42/45357FE18950/SIP/Media/sipSPISetStreamInfo:
caps.stream_count=0,caps.stream[0].stream_type=0xFFFF, caps.stream_list.xmitFunc=
Mar 1 20:10:56.618: //42/45357FE18950/SIP/Media/sipSPISetStreamInfo: ??unknown??, caps.stream_list.context=
Mar 1 20:10:56.618: //42/45357FE18950/SIP/Media/sipSPISetStreamInfo: 0x0 (gccb)
Mar 1 20:10:56.618: //42/45357FE18950/SIP/Info/ccsip_caps_ind: Load DSP with codec : g711ulaw, Bytes=160
Mar 1 20:10:56.618: //42/45357FE18950/SIP/Info/ccsip_caps_ind: ccsip_caps_ind: ccb->flags_ipip = 0x403
Mar 1 20:10:56.618: //42/45357FE18950/CCAPI/cc_api_caps_ack:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=42,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=1)
Mar 1 20:10:56.622: //43/000000000000/SIP/Info/ccsip_caps_ack: Set forking flag to 0x0
Mar 1 20:10:56.622: //43/000000000000/SIP/Media/sipSPIUpdCallWithSdpInfo:
Stream type : voice+dtmf
Media line : 1
State : STREAM_ADDING (2)
Callid : 43
Negotiated Codec : g711ulaw, bytes :160
Negotiated DTMF relay : rtp-nte
Negotiated NTE payload : 101
Negotiated CN payload : 0
Media Srce Addr/Port : 172.16.0.241:16502
Media Dest Addr/Port : 172.16.1.218:55006

Mar 1 20:10:56.622: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD passed.
Mar 1 20:10:56.622: //43/45357FE18950/CCAPI/cc_api_call_cut_progress:
Interface=0x84F61A50, Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1),
Cause Value=0
Mar 1 20:10:56.622: //43/45357FE18950/CCAPI/cc_api_call_cut_progress:
Call Entry(Responsed=TRUE)
Mar 1 20:10:56.626: //43/000000000000/SIP/Info/HandleSIP1xxSessionProgress: ccsip_api_call_cut_progress returned: SIP_SUCCESS
Mar 1 20:10:56.626: //43/000000000000/SIP/State/sipSPIChangeState: 0x856CA76C : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
Mar 1 20:10:56.630: //43/000000000000/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Mar 1 20:10:56.754: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.35.16:49750;branch=z9hG4bKeb8932a5
From: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
To: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
Date: Fri, 01 Mar 2002 20:10:56 GMT
Call-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1137 PRACK
Content-Length: 0

Mar 1 20:10:57.126: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x85196EBC
Mar 1 20:10:57.126: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.1.218, port=5060, connid=6, transport=tcp
Mar 1 20:10:57.130: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
FROM: "kris test"<sip:35924910702@172.16.0.241>;tag=454A018-AD3
TO: <sip:+35924910610@172.16.1.218>;tag=80ab175cce;epid=8379FDC2F7
CSEQ: 101 INVITE
CALL-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
VIA: SIP/2.0/TCP 172.16.0.241;branch=z9hG4bK5DD84
CONTACT: <sip:win2012-04.valio.local:5060;transport=Tcp;maddr=172.16.1.218>
CONTENT-LENGTH: 0
ALLOW: CANCEL
ALLOW: BYE
ALLOW: UPDATE
ALLOW: PRACK
SERVER: RTCC/6.0.0.0 MediationServer

Mar 1 20:10:57.134: //43/000000000000/SIP/Info/HandleSIP1xxSessionProgressNoSDP: No SDP or GTD Body in 183 Session Progress.
Mar 1 20:10:57.134: //43/000000000000/SIP/Info/HandleSIP1xxSessionProgressNoSDP: Transaction Complete. Lock on Facilities released.
Mar 1 20:10:57.134: //43/000000000000/SIP/State/sipSPIChangeState: 0x856CA76C : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
Mar 1 20:10:57.142: //-1/xxxxxxxxxxxx/SIP/Info/httpish_msg_process_network_msg: Content Length 0, Bytes Remaining 839
Mar 1 20:10:57.146: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x85196EBC
Mar 1 20:10:57.146: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.1.218, port=5060, connid=6, transport=tcp
Mar 1 20:10:57.150: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing
FROM: "kris test"<sip:35924910702@172.16.0.241>;tag=454A018-AD3
TO: <sip:+35924910610@172.16.1.218>;tag=80ab175cce;epid=8379FDC2F7
CSEQ: 101 INVITE
CALL-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
VIA: SIP/2.0/TCP 172.16.0.241;branch=z9hG4bK5DD84
CONTACT: <sip:win2012-04.valio.local:5060;transport=Tcp;maddr=172.16.1.218>
CONTENT-LENGTH: 0
ALLOW: CANCEL
ALLOW: BYE
ALLOW: UPDATE
ALLOW: PRACK
SERVER: RTCC/6.0.0.0 MediationServer

Mar 1 20:10:57.154: //43/000000000000/SIP/Info/ccsip_api_call_alert: SDP Body either absent or ignored in 180 RINGING:- will wait for 200 OK to do negotiation.
Mar 1 20:10:57.154: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD passed.
Mar 1 20:10:57.154: //43/45357FE18950/CCAPI/cc_api_call_alert:
Interface=0x84F61A50, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
Mar 1 20:10:57.154: //43/45357FE18950/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
Mar 1 20:10:57.158: //43/000000000000/SIP/Info/HandleSIP1xxRinging: ccsip_api_call_alert returned: SIP_SUCCESS
Mar 1 20:10:57.158: //43/000000000000/SIP/State/sipSPIChangeState: 0x856CA76C : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
Mar 1 20:10:57.158: //43/000000000000/SIP/Info/HandleSIP1xxRinging: Transaction Complete. Lock on Facilities released.
Mar 1 20:10:57.158: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x85196EBC
Mar 1 20:10:57.158: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.1.218, port=5060, connid=6, transport=tcp
Mar 1 20:10:57.162: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
FROM: "kris test"<sip:35924910702@172.16.0.241>;tag=454A018-AD3
TO: <sip:+35924910610@172.16.1.218>;tag=80ab175cce;epid=8379FDC2F7
CSEQ: 101 INVITE
CALL-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
VIA: SIP/2.0/TCP 172.16.0.241;branch=z9hG4bK5DD84
CONTACT: <sip:win2012-04.valio.local:5060;transport=Tcp;maddr=172.16.1.218>
CONTENT-LENGTH: 254
SUPPORTED: timer
SUPPORTED: 100rel
CONTENT-TYPE: application/sdp
ALLOW: ACK
SERVER: RTCC/6.0.0.0 MediationServer
Allow: CANCEL,BYE,INVITE,PRACK,UPDATE
Session-Expires: 1800;refresher=uas
Min-SE: 1800

v=0
o=- 86 1 IN IP4 172.16.1.218
s=session
c=IN IP4 172.16.1.218
b=CT:1000
t=0 0
m=audio 55006 RTP/AVP 0 101
c=IN IP4 172.16.1.218
a=rtcp:55007
a=label:Audio
a=sendrecv
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

Mar 1 20:10:57.166: //43/000000000000/SIP/Info/sipSPIUACSessionTimer: Refresh param: uas
Mar 1 20:10:57.170: //43/000000000000/SIP/Info/sipSPIUACSessionTimer: SE Value: 1800
Mar 1 20:10:57.170: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetGtdBody: No valid GTD body found.
Mar 1 20:10:57.170: //43/000000000000/SIP/Info/sipSPIhandle200OKInvite: Transaction active. Facilities will be queued.
Mar 1 20:10:57.170: //43/000000000000/SIP/Info/sipSPIhandle200OKInvite: *** This ccb is the parent
SIP: Warning: Unrecognized attribute (label)
Mar 1 20:10:57.174: //43/000000000000/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
Mar 1 20:10:57.174: //43/000000000000/SIP/Info/sipSPIDoAudioNegotiation: Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1
Mar 1 20:10:57.174: //43/000000000000/SIP/Info/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled
Mar 1 20:10:57.174: //43/000000000000/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
Mar 1 20:10:57.174: //43/000000000000/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events.
Mar 1 20:10:57.174: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0
Mar 1 20:10:57.178: //43/000000000000/SIP/Info/sip_do_nse_negotiation: Remote NSE payload = local one = 0, Use it
Mar 1 20:10:57.178: //43/000000000000/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
Mar 1 20:10:57.178: //43/000000000000/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
Mar 1 20:10:57.178: //43/000000000000/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=172.16.1.218, dest_port=55006
Mar 1 20:10:57.178: //43/000000000000/SIP/Media/sipSPICompareStreams: stream 1 dest_port: old=55006 new=55006
Mar 1 20:10:57.178: //43/000000000000/SIP/Media/sipSPIGetNewLocalMediaDirection:
New Remote Media Direction = SENDRECV
Present Local Media Direction = SENDRECV
New Local Media Direction = SENDRECV
retVal = 0

Mar 1 20:10:57.178: //43/000000000000/SIP/Media/sipSPICompareStreams: Flags set for stream 1: RTP_CHANGE=No CAPS_CHANGE=No
Mar 1 20:10:57.182: //43/000000000000/SIP/Media/sipSPICompareSDP: Flags set for call: NEW_MEDIA=No DSPDNLD_REQD=No IPIP_MEDIA=No
Mar 1 20:10:57.182: //43/000000000000/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

Mar 1 20:10:57.182: //43/000000000000/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.16.0.241
Mar 1 20:10:57.182: //43/000000000000/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 43 peer 42 flags 0x407
Mar 1 20:10:57.182: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 43, sdp 0x853F56A8 channels 0x856CC31C
Mar 1 20:10:57.186: //43/000000000000/SIP/Info/copy_channels:
callId 43 size 80 ptr 0x84E385B0)
SIP: Attribute ptime, level 1 instance 1 not found.
Mar 1 20:10:57.186: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 0 mline 1
Mar 1 20:10:57.186: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711ulaw
Mar 1 20:10:57.186: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Mar 1 20:10:57.186: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation done: stream->negotiated_ptime=0,stream->negotiated_codec_bytes=160, coverted ptime=20 stream->mline_index=1, media_ndx=1
Mar 1 20:10:57.186: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 5 ptype 0 time 20, bytes 160 as channel 0 mline 1 ss 0 172.16.1.218:55006
Mar 1 20:10:57.186: //43/000000000000/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 101 mline 1
Mar 1 20:10:57.190: //43/000000000000/SIP/Media/sipSPIUpdCallWithSdpInfo:
Stream type : voice+dtmf
Media line : 1
State : STREAM_ACTIVE (5)
Callid : 43
Negotiated Codec : g711ulaw, bytes :160
Negotiated DTMF relay : rtp-nte
Negotiated NTE payload : 101
Negotiated CN payload : 0
Media Srce Addr/Port : 172.16.0.241:16502
Media Dest Addr/Port : 172.16.1.218:55006

Mar 1 20:10:57.190: //43/000000000000/SIP/Info/sipSPIProcessMediaChanges: sipSPIProcessMediaChanges
Mar 1 20:10:57.314: //42/45357FE18950/SIP/Info/sipSPIProcessHoldTimerForCall: Media IP Addr 172.16.35.16, RTCP Type 3
Mar 1 20:10:57.314: //42/45357FE18950/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
Mar 1 20:11:12.102: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_sockerror_to_spi: Sending TCP Remote Closure to SPI, connid: 2
Mar 1 20:11:12.102: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWSocketException: context=0x00000000
Mar 1 20:11:12.102: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessSocketExceptions: gConnTab=0x85196EBC, addr=172.16.1.218, port=54132, connid=2, transport=tcp
Mar 1 20:11:12.102: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostCloseConnection: Posting TCP conn close for addr=172.16.1.218, port=54132, connid=2
Mar 1 20:11:12.106: //-1/xxxxxxxxxxxx/SIP/Transport/sipDeleteConnInstance: Deleted conn=0x851974A4, connid=2, addr=172.16.1.218, port=54132, transport=tcp
Mar 1 20:11:12.106: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_purge_entry: Socket fd: 2 closed for connid 2 with address: 172.16.1.218, remote port: 54132
Mar 1 20:11:12.114: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
Mar 1 20:11:12.114: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.1.218, port=54167, connid=2, transport=tcp
Mar 1 20:11:12.114: //-1/xxxxxxxxxxxx/SIP/Transport/sipCreateConnInstance: Created new accptd conn=0x85197E40, connid=2, addr=172.16.1.218, port=54167, transport=tcp
Mar 1 20:11:12.114: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
Mar 1 20:11:12.114: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.1.218, port=54167, connid=2, transport=tcp
Mar 1 20:11:12.114: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:vg.valio.local SIP/2.0
FROM: <sip:win2012-04.valio.local:5060;transport=Tcp;ms-opaque=841957df6d23ece8>;epid=8379FDC2F7;tag=f0663fcf61
TO: <sip:vg.valio.local>
CSEQ: 9555 OPTIONS
CALL-ID: 854acd36565d4220a70b2a5f3107aa71
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 172.16.1.218:54167;branch=z9hG4bK57e13d3
CONTACT: <sip:win2012-04.valio.local:5060;transport=Tcp;maddr=172.16.1.218>
CONTENT-LENGTH: 0
USER-AGENT: RTCC/6.0.0.0 MediationServer

Mar 1 20:11:12.118: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 172.16.1.218,Port 54167, Transport 2, SentBy Port 54167
Mar 1 20:11:12.118: //-1/4F9217BA8953/SIP/State/sipSPIChangeState: 0x856C34E8 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Mar 1 20:11:12.122: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.1.218,Port 54167, Transport 2, SentBy Port 5060
Mar 1 20:11:12.122: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
Mar 1 20:11:12.122: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.1.218,Port 54167, Transport 2, SentBy Port 54167
Mar 1 20:11:12.126: //-1/4F9217BA8953/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
Mar 1 20:11:12.126: //-1/4F9217BA8953/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
Mar 1 20:11:12.130: //-1/4F9217BA8953/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
Mar 1 20:11:12.130: //-1/4F9217BA8953/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
Mar 1 20:11:12.130: //-1/4F9217BA8953/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
Mar 1 20:11:12.130: //-1/4F9217BA8953/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
Mar 1 20:11:12.130: //-1/4F9217BA8953/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
Mar 1 20:11:12.130: //-1/4F9217BA8953/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
Mar 1 20:11:12.134: //-1/4F9217BA8953/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.16.0.241
Mar 1 20:11:12.138: //-1/4F9217BA8953/SIP/Transport/sipSPISendOptionsResponse: Sending OPTIONS Response to the transport layer
Mar 1 20:11:12.138: //-1/4F9217BA8953/SIP/Transport/sipSPITransportSendMessage: msg=0x85827248, addr=172.16.1.218, port=54167, sentBy_port=54167, is_req=0, transport=2, switch=0, callBack=0x80F34F14
Mar 1 20:11:12.138: //-1/4F9217BA8953/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:12.138: //-1/4F9217BA8953/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:12.138: //-1/4F9217BA8953/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x85827248 to default port=54167
Mar 1 20:11:12.142: //-1/4F9217BA8953/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x85827248
Mar 1 20:11:12.142: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x85827248, addr=172.16.1.218, port=54167, connId=2 for TCP
Mar 1 20:11:12.142: //-1/4F9217BA8953/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Mar 1 20:11:12.142: //-1/4F9217BA8953/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 856C34E8
Mar 1 20:11:12.146: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_sendmsg: Socket blocked data queued
Mar 1 20:11:12.150: //-1/xxxxxxxxxxxx/SIP/Info/sipTcpQueueSendData: Data queued length: 240
Mar 1 20:11:12.158: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:12.166: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:12.174: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:12.182: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:12.190: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:12.198: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:12.206: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.1.218:54167;branch=z9hG4bK57e13d3
From: <sip:win2012-04.valio.local:5060;transport=Tcp;ms-opaque=841957df6d23ece8>;epid=8379FDC2F7;tag=f0663fcf61
To: <sip:vg.valio.local>;tag=454E380-4D4
Date: Fri, 01 Mar 2002 20:11:12 GMT
Call-ID: 854acd36565d4220a70b2a5f3107aa71
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 9555 OPTIONS
Supported: 100rel,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Accept: application/sdp
Allow-Events: telephone-event
Content-Length: 167
Content-Type: application/sdp

v=0
o=CiscoSystemsSIP-GW-UserAgent 4387 5935 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 172.16.0.241

Mar 1 20:11:31.190: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x85196EBC
Mar 1 20:11:31.190: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.1.218, port=5060, connid=6, transport=tcp
Mar 1 20:11:31.194: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:35924910702@172.16.0.241:5060;transport=tcp SIP/2.0
FROM: <sip:+35924910610@172.16.1.218>;epid=8379FDC2F7;tag=80ab175cce
TO: <sip:35924910702@172.16.0.241>;tag=454A018-AD3
CSEQ: 1 INVITE
CALL-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 172.16.1.218:5060;branch=z9hG4bKf9bffc7a
CONTACT: <sip:win2012-04.valio.local:5060;transport=Tcp;maddr=172.16.1.218;ms-opaque=841957df6d23ece8>
CONTENT-LENGTH: 254
SUPPORTED: timer
SUPPORTED: 100rel
USER-AGENT: RTCC/6.0.0.0 MediationServer
CONTENT-TYPE: application/sdp
Session-Expires: 1800
Min-SE: 1800

v=0
o=- 86 2 IN IP4 172.16.1.218
s=session
c=IN IP4 172.16.1.218
b=CT:1000
t=0 0
m=audio 55006 RTP/AVP 0 101
c=IN IP4 172.16.1.218
a=rtcp:55007
a=label:Audio
a=sendrecv
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

Mar 1 20:11:31.198: //43/45357FE18950/SIP/Info/ccsip_new_msg_preprocessor: ****Found CCB in UAC table

Mar 1 20:11:31.198: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.1.218,Port 5060, Transport 2, SentBy Port 5060
Mar 1 20:11:31.198: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
Mar 1 20:11:31.202: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.1.218,Port 5060, Transport 2, SentBy Port 5060
Mar 1 20:11:31.202: //43/45357FE18950/SIP/Info/sact_active_new_message_request: Case of Mid-Call INVITE/UPDATE in ACTIVE STATE
Mar 1 20:11:31.202: //42/45357FE18950/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=FALSE, Tone=Null,
Tone Direction=Sum Network, Params=0x0, Call Id=42
Mar 1 20:11:31.206: //43/45357FE18950/SIP/Info/sipSPIUASSessionTimer: Min-SE Header: 1800
Mar 1 20:11:31.206: //43/45357FE18950/SIP/Info/sipSPIUASSessionTimer: SE Value: 1800
SIP: Warning: Unrecognized attribute (label)
Mar 1 20:11:31.210: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetGtdBody: No valid GTD body found.
Mar 1 20:11:31.210: //43/45357FE18950/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
Mar 1 20:11:31.210: //43/45357FE18950/SIP/Info/sipSPIDoAudioNegotiation: Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1
Mar 1 20:11:31.210: //43/45357FE18950/SIP/Info/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled
Mar 1 20:11:31.210: //43/45357FE18950/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
Mar 1 20:11:31.210: //43/45357FE18950/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events.
Mar 1 20:11:31.214: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0
Mar 1 20:11:31.214: //43/45357FE18950/SIP/Info/sip_do_nse_negotiation: Remote NSE payload = local one = 0, Use it
Mar 1 20:11:31.214: //43/45357FE18950/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
Mar 1 20:11:31.214: //43/45357FE18950/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
Mar 1 20:11:31.214: //43/45357FE18950/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=172.16.1.218, dest_port=55006
Mar 1 20:11:31.214: //43/45357FE18950/SIP/Media/sipSPICompareStreams: stream 1 dest_port: old=55006 new=55006
Mar 1 20:11:31.218: //43/45357FE18950/SIP/Media/sipSPIGetNewLocalMediaDirection:
New Remote Media Direction = SENDRECV
Present Local Media Direction = SENDRECV
New Local Media Direction = SENDRECV
retVal = 0

Mar 1 20:11:31.218: //43/45357FE18950/SIP/Media/sipSPICompareStreams: Flags set for stream 1: RTP_CHANGE=No CAPS_CHANGE=No
Mar 1 20:11:31.218: //43/45357FE18950/SIP/Media/sipSPICompareSDP: Flags set for call: NEW_MEDIA=No DSPDNLD_REQD=No IPIP_MEDIA=No
Mar 1 20:11:31.218: //43/45357FE18950/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

Mar 1 20:11:31.218: //43/45357FE18950/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.16.0.241
Mar 1 20:11:31.222: //43/45357FE18950/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 43 peer 42 flags 0x50F
Mar 1 20:11:31.222: //43/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 43, sdp 0x8544C890 channels 0x856CC31C
Mar 1 20:11:31.222: //43/45357FE18950/SIP/Info/copy_channels:
callId 43 size 80 ptr 0x840BB654)
SIP: Attribute ptime, level 1 instance 1 not found.
Mar 1 20:11:31.222: //43/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Call 43 setting xmit func to rtp
Mar 1 20:11:31.222: //43/45357FE18950/SIP/Info/ccsip_remote_codec_download_done:
callId 43 context 0x858AC940
Mar 1 20:11:31.222: //43/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 0 mline 1
Mar 1 20:11:31.222: //43/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711ulaw
Mar 1 20:11:31.222: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Mar 1 20:11:31.226: //43/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation done: stream->negotiated_ptime=0,stream->negotiated_codec_bytes=160, coverted ptime=20 stream->mline_index=1, media_ndx=1
Mar 1 20:11:31.226: //43/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 5 ptype 0 time 20, bytes 160 as channel 0 mline 1 ss 0 172.16.1.218:55006
Mar 1 20:11:31.226: //43/45357FE18950/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 101 mline 1
Mar 1 20:11:31.226: //43/45357FE18950/SIP/Info/sipSPI_ipip_report_media_to_peer:
Report reINVITE media
Mar 1 20:11:31.226: //43/45357FE18950/SIP/Info/copy_channels:
callId 43 size 80 ptr 0x840BB654)
Mar 1 20:11:31.226: //43/45357FE18950/CCAPI/cc_api_modify_media_ind:
IFType=0x3, Source Call Id=0x2B, Destination Call Id=0x2A,
Codec=1, Codec Bytes=3
Mar 1 20:11:31.230: //42/45357FE18950/SIP/Info/ccsip_modify_media_ind: codec=0x1 modem_redundancy=2237362416 callID 42 peer callID 43
Mar 1 20:11:31.230: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_MEDIA_CHANGED
Mar 1 20:11:31.230: //43/45357FE18950/SIP/Media/sipSPIUpdCallWithSdpInfo:
Stream type : voice+dtmf
Media line : 1
State : STREAM_ACTIVE (5)
Callid : 43
Negotiated Codec : g711ulaw, bytes :160
Negotiated DTMF relay : rtp-nte
Negotiated NTE payload : 101
Negotiated CN payload : 0
Media Srce Addr/Port : 172.16.0.241:16502
Media Dest Addr/Port : 172.16.1.218:55006

Mar 1 20:11:31.230: //43/45357FE18950/SIP/Info/sipSPIProcessMediaChanges: sipSPIProcessMediaChanges
Mar 1 20:11:31.230: //43/45357FE18950/SIP/Info/sipSPIIncomingCallSDP: SDP already there use old SDP and update media if needed
Mar 1 20:11:31.234: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
Mar 1 20:11:31.234: //43/45357FE18950/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Setting stream 1 portnum to 16502
Mar 1 20:11:31.234: //43/45357FE18950/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Negotiated method of dtmf relayand pyld: 6 101

Mar 1 20:11:31.234: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_MEDIA_EVENT
Mar 1 20:11:31.234: //43/45357FE18950/SIP/State/sipSPIChangeState: 0x856CA76C : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_MIDCALL_LOCAL_RESP_PENDING, SUBSTATE_NONE)
Mar 1 20:11:31.238: //43/45357FE18950/SIP/Info/sipSPIHandleIncomingMidCallInvite: ccsip_api_request_offer returned: SIP_SUCCESS
Mar 1 20:11:31.238: //43/45357FE18950/SIP/Event/sipSPICreateRpid: Received Octet3A=0x80 -> Setting ;screen=no ;privacy=off
Mar 1 20:11:31.238: //43/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x85827488, addr=172.16.1.218, port=5060, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=0x00000000
Mar 1 20:11:31.242: //43/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:31.242: //43/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:31.242: //43/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x85827488 to default port=5060
Mar 1 20:11:31.242: //43/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x85827488
Mar 1 20:11:31.242: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x85827488, addr=172.16.1.218, port=5060, connId=6 for TCP
Mar 1 20:11:31.242: //43/45357FE18950/SIP/Info/sipSPIProcessContactInfo: Previous Hop 172.16.1.218:5060
Mar 1 20:11:31.242: //42/45357FE18950/SIP/Info/act_active_media_changed: New codec = 5
Mar 1 20:11:31.246: //42/45357FE18950/SIP/Transport/sipSPISendErrorResponse: Sending ERROR Response to the transport layer
Mar 1 20:11:31.246: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x85827248, addr=172.16.35.16, port=49750, sentBy_port=49750, is_req=0, transport=2, switch=0, callBack=0x00000000
Mar 1 20:11:31.250: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:31.250: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:31.250: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x85827248 to default port=49750
Mar 1 20:11:31.250: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x85827248
Mar 1 20:11:31.250: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x85827248, addr=172.16.35.16, port=49750, connId=4 for TCP
Mar 1 20:11:31.250: //43/45357FE18950/SIP/Info/act_handle_app_media_event: Received media sip event SIP_RESPONSE_ANSWER
Mar 1 20:11:31.258: //43/45357FE18950/SIP/Event/sipSPICreateRpid: Received Octet3A=0x80 -> Setting ;screen=no ;privacy=off
Mar 1 20:11:31.258: //43/45357FE18950/SIP/Transport/sipSPISendInviteResponse: Sending 200OK Response to the Transport Layer
Mar 1 20:11:31.258: //43/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x858276C8, addr=172.16.1.218, port=5060, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=0x80F33328
Mar 1 20:11:31.258: //43/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:31.262: //43/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:31.262: //43/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x858276C8 to default port=5060
Mar 1 20:11:31.262: //43/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x858276C8
Mar 1 20:11:31.262: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x858276C8, addr=172.16.1.218, port=5060, connId=6 for TCP
Mar 1 20:11:31.262: //43/45357FE18950/SIP/Info/sentResponseStartConnectTimer: Sent Response, start ConnectTimer
Mar 1 20:11:31.262: //43/45357FE18950/SIP/Info/sipSPIProcessHoldTimerForCall: Media IP Addr 172.16.1.218, RTCP Type 3
Mar 1 20:11:31.262: //43/45357FE18950/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
Mar 1 20:11:31.270: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 172.16.1.218:5060;branch=z9hG4bKf9bffc7a
From: <sip:+35924910610@172.16.1.218>;tag=80ab175cce;epid=8379FDC2F7
To: "kris test" <sip:35924910702@172.16.0.241>;tag=454A018-AD3
Date: Fri, 01 Mar 2002 20:11:31 GMT
Call-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 INVITE
Allow-Events: telephone-event
Remote-Party-ID: "kris test" <sip:35924910702@172.16.0.241>;party=called;screen=no;privacy=off
Content-Length: 0

Mar 1 20:11:31.274: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 488 Not Acceptable Media
Via: SIP/2.0/TCP 172.16.35.16:49750;branch=z9hG4bKf736f4f
From: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
To: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
Call-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
Warning: 304 172.16.0.241 "Media Type(s) Unavailable"
CSeq: 1136 INVITE
Content-Length: 0

Mar 1 20:11:31.274: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.1.218:5060;branch=z9hG4bKf9bffc7a
From: <sip:+35924910610@172.16.1.218>;tag=80ab175cce;epid=8379FDC2F7
To: "kris test" <sip:35924910702@172.16.0.241>;tag=454A018-AD3
Date: Fri, 01 Mar 2002 20:11:31 GMT
Call-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 INVITE
Session-Expires: 1800;refresher=uac
Require: timer
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Supported: replaces
Allow-Events: telephone-event
Remote-Party-ID: "kris test" <sip:35924910702@172.16.0.241>;party=called;screen=no;privacy=off
Contact: <sip:35924910702@172.16.0.241:5060;transport=tcp>
Content-Type: application/sdp
Content-Length: 235

v=0
o=CiscoSystemsSIP-GW-UserAgent 1360 9685 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 16502 RTP/AVP 0 101
c=IN IP4 172.16.0.241
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

Mar 1 20:11:31.286: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x85196EBC
Mar 1 20:11:31.286: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.1.218, port=5060, connid=6, transport=tcp
Mar 1 20:11:31.286: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:35924910702@172.16.0.241:5060;transport=tcp SIP/2.0
FROM: <sip:+35924910610@172.16.1.218>;epid=8379FDC2F7;tag=80ab175cce
TO: <sip:35924910702@172.16.0.241>;tag=454A018-AD3
CSEQ: 1 ACK
CALL-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 172.16.1.218:5060;branch=z9hG4bK95b007b
CONTENT-LENGTH: 0
USER-AGENT: RTCC/6.0.0.0 MediationServer

Mar 1 20:11:31.290: //43/45357FE18950/SIP/Info/ccsip_new_msg_preprocessor: ****Found CCB in UAC table

Mar 1 20:11:31.290: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.1.218,Port 5060, Transport 2, SentBy Port 5060
Mar 1 20:11:31.290: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
Mar 1 20:11:31.294: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.1.218,Port 5060, Transport 2, SentBy Port 5060
Mar 1 20:11:31.294: //43/45357FE18950/SIP/Info/sipSPIProcessIncomingAckForMidCall: Processing MidCall ACK
Mar 1 20:11:31.294: //43/45357FE18950/SIP/State/sipSPIChangeState: 0x856CA76C : State change from (STATE_MIDCALL_LOCAL_RESP_PENDING, SUBSTATE_NONE) to (STATE_ACTIVE, SUBSTATE_NONE)
Mar 1 20:11:31.294: //43/45357FE18950/SIP/Info/sipSPICreateAndStartRtpTimer:
Mar 1 20:11:31.294: //43/45357FE18950/SIP/Info/sipSPICreateAndStartRtpTimer: Media Inactivity Timer is disabled.
Mar 1 20:11:31.754: //42/45357FE18950/SIP/Transport/sipSPISendInviteResponse: Sending 200OK Response to the Transport Layer
Mar 1 20:11:31.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x858277E8, addr=172.16.35.16, port=49750, sentBy_port=49750, is_req=0, transport=2, switch=0, callBack=0x00000000
Mar 1 20:11:31.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:31.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:31.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x858277E8 to default port=49750
Mar 1 20:11:31.758: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x858277E8
Mar 1 20:11:31.758: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x858277E8, addr=172.16.35.16, port=49750, connId=4 for TCP
Mar 1 20:11:31.762: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.35.16:49750;branch=z9hG4bKf736f4f
From: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
To: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
Date: Fri, 01 Mar 2002 20:10:57 GMT
Call-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1136 INVITE
Session-Expires: 1800;refresher=uac
Require: timer
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Supported: replaces
Allow-Events: telephone-event
Contact: <sip:+35924910610@172.16.0.241:5060;transport=tcp>
Content-Type: application/sdp
Content-Length: 271

v=0
o=CiscoSystemsSIP-GW-UserAgent 7396 1349 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 16732 RTP/AVP 0 13 101
c=IN IP4 172.16.0.241
a=rtpmap:0 PCMU/8000
a=rtpmap:13 CN/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

Mar 1 20:11:32.754: //42/45357FE18950/SIP/Transport/sipSPISendInviteResponse: Sending 200OK Response to the Transport Layer
Mar 1 20:11:32.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x858277E8, addr=172.16.35.16, port=49750, sentBy_port=49750, is_req=0, transport=2, switch=0, callBack=0x00000000
Mar 1 20:11:32.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:32.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:32.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x858277E8 to default port=49750
Mar 1 20:11:32.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x858277E8
Mar 1 20:11:32.758: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x858277E8, addr=172.16.35.16, port=49750, connId=4 for TCP
Mar 1 20:11:32.758: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.35.16:49750;branch=z9hG4bKf736f4f
From: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
To: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
Date: Fri, 01 Mar 2002 20:10:57 GMT
Call-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1136 INVITE
Session-Expires: 1800;refresher=uac
Require: timer
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Supported: replaces
Allow-Events: telephone-event
Contact: <sip:+35924910610@172.16.0.241:5060;transport=tcp>
Content-Type: application/sdp
Content-Length: 271

v=0
o=CiscoSystemsSIP-GW-UserAgent 7396 1349 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 16732 RTP/AVP 0 13 101
c=IN IP4 172.16.0.241
a=rtpmap:0 PCMU/8000
a=rtpmap:13 CN/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

Mar 1 20:11:34.754: //42/45357FE18950/SIP/Transport/sipSPISendInviteResponse: Sending 200OK Response to the Transport Layer
Mar 1 20:11:34.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x858277E8, addr=172.16.35.16, port=49750, sentBy_port=49750, is_req=0, transport=2, switch=0, callBack=0x00000000
Mar 1 20:11:34.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:34.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:34.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x858277E8 to default port=49750
Mar 1 20:11:34.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x858277E8
Mar 1 20:11:34.758: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x858277E8, addr=172.16.35.16, port=49750, connId=4 for TCP
Mar 1 20:11:34.758: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.35.16:49750;branch=z9hG4bKf736f4f
From: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
To: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
Date: Fri, 01 Mar 2002 20:10:57 GMT
Call-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1136 INVITE
Session-Expires: 1800;refresher=uac
Require: timer
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Supported: replaces
Allow-Events: telephone-event
Contact: <sip:+35924910610@172.16.0.241:5060;transport=tcp>
Content-Type: application/sdp
Content-Length: 271

v=0
o=CiscoSystemsSIP-GW-UserAgent 7396 1349 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 16732 RTP/AVP 0 13 101
c=IN IP4 172.16.0.241
a=rtpmap:0 PCMU/8000
a=rtpmap:13 CN/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

Mar 1 20:11:38.754: //42/45357FE18950/SIP/Transport/sipSPISendInviteResponse: Sending 200OK Response to the Transport Layer
Mar 1 20:11:38.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x858277E8, addr=172.16.35.16, port=49750, sentBy_port=49750, is_req=0, transport=2, switch=0, callBack=0x00000000
Mar 1 20:11:38.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:38.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:38.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x858277E8 to default port=49750
Mar 1 20:11:38.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x858277E8
Mar 1 20:11:38.758: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x858277E8, addr=172.16.35.16, port=49750, connId=4 for TCP
Mar 1 20:11:38.758: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.35.16:49750;branch=z9hG4bKf736f4f
From: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
To: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
Date: Fri, 01 Mar 2002 20:10:57 GMT
Call-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1136 INVITE
Session-Expires: 1800;refresher=uac
Require: timer
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Supported: replaces
Allow-Events: telephone-event
Contact: <sip:+35924910610@172.16.0.241:5060;transport=tcp>
Content-Type: application/sdp
Content-Length: 271

v=0
o=CiscoSystemsSIP-GW-UserAgent 7396 1349 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 16732 RTP/AVP 0 13 101
c=IN IP4 172.16.0.241
a=rtpmap:0 PCMU/8000
a=rtpmap:13 CN/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

Mar 1 20:11:42.754: //42/45357FE18950/SIP/Transport/sipSPISendInviteResponse: Sending 200OK Response to the Transport Layer
Mar 1 20:11:42.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x858277E8, addr=172.16.35.16, port=49750, sentBy_port=49750, is_req=0, transport=2, switch=0, callBack=0x00000000
Mar 1 20:11:42.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:42.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:42.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x858277E8 to default port=49750
Mar 1 20:11:42.758: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x858277E8
Mar 1 20:11:42.758: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x858277E8, addr=172.16.35.16, port=49750, connId=4 for TCP
Mar 1 20:11:42.762: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.35.16:49750;branch=z9hG4bKf736f4f
From: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
To: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
Date: Fri, 01 Mar 2002 20:10:57 GMT
Call-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1136 INVITE
Session-Expires: 1800;refresher=uac
Require: timer
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Supported: replaces
Allow-Events: telephone-event
Contact: <sip:+35924910610@172.16.0.241:5060;transport=tcp>
Content-Type: application/sdp
Content-Length: 271

v=0
o=CiscoSystemsSIP-GW-UserAgent 7396 1349 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 16732 RTP/AVP 0 13 101
c=IN IP4 172.16.0.241
a=rtpmap:0 PCMU/8000
a=rtpmap:13 CN/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

Mar 1 20:11:46.754: //42/45357FE18950/SIP/Transport/sipSPISendInviteResponse: Sending 200OK Response to the Transport Layer
Mar 1 20:11:46.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x858277E8, addr=172.16.35.16, port=49750, sentBy_port=49750, is_req=0, transport=2, switch=0, callBack=0x00000000
Mar 1 20:11:46.754: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:46.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:46.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x858277E8 to default port=49750
Mar 1 20:11:46.754: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x858277E8
Mar 1 20:11:46.758: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x858277E8, addr=172.16.35.16, port=49750, connId=4 for TCP
Mar 1 20:11:46.758: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.35.16:49750;branch=z9hG4bKf736f4f
From: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
To: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
Date: Fri, 01 Mar 2002 20:10:57 GMT
Call-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1136 INVITE
Session-Expires: 1800;refresher=uac
Require: timer
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Supported: replaces
Allow-Events: telephone-event
Contact: <sip:+35924910610@172.16.0.241:5060;transport=tcp>
Content-Type: application/sdp
Content-Length: 271

v=0
o=CiscoSystemsSIP-GW-UserAgent 7396 1349 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 16732 RTP/AVP 0 13 101
c=IN IP4 172.16.0.241
a=rtpmap:0 PCMU/8000
a=rtpmap:13 CN/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

Mar 1 20:11:50.750: //42/45357FE18950/SIP/Error/act_active_wait_ack: Out of retries
Mar 1 20:11:50.750: //42/45357FE18950/SIP/Info/ccsip_set_cc_cause_for_spi_err: Categorized cause:102, category:129
Mar 1 20:11:50.750: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[42], src[6]
Mar 1 20:11:50.750: //42/45357FE18950/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(127) for incoming call
Mar 1 20:11:50.750: //42/45357FE18950/SIP/State/sipSPIChangeState: 0x856B760C : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
Mar 1 20:11:50.750: //42/45357FE18950/CCAPI/cc_api_call_disconnected:
Cause Value=127, Interface=0x84F61A50, Call Id=42
Mar 1 20:11:50.750: //42/45357FE18950/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=127, Retry Count=0)
Mar 1 20:11:50.754: //42/45357FE18950/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
Mar 1 20:11:50.758: //42/45357FE18950/SIP/Transport/sipSPISendBye: Sending BYE to the transport layer
Mar 1 20:11:50.758: //42/45357FE18950/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Mar 1 20:11:50.758: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x858277E8, addr=172.16.35.16, port=5060, sentBy_port=0, is_req=1, transport=2, switch=0, callBack=0x80F32ED8
Mar 1 20:11:50.758: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:50.758: //42/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:50.758: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostRequestConnection: Posting TCP conn create request for addr=172.16.35.16, port=5060, context=0x85196EBC
Mar 1 20:11:50.758: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetConnWaitTimer: Wait timer set for connection=0x84F61054,addr=172.16.35.16, port=5060
Mar 1 20:11:50.762: //-1/xxxxxxxxxxxx/SIP/Transport/sipCreateConnInstance: Created new initiated conn=0x84F61054, connid=-1, addr=172.16.35.16, port=5060, transport=tcp
Mar 1 20:11:50.762: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering ccb=0x856B760C with connection=0x84F61054
Mar 1 20:11:50.762: //42/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Deferred sending msg=0x858277E8
Mar 1 20:11:50.762: //42/45357FE18950/CCAPI/ccConferenceDestroy:
Conference Id=0x15, Tag=0x0
Mar 1 20:11:50.766: //42/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x15, Source Interface=0x84F61A50, Source Call Id=42,
Destination Call Id=43, Disposition=0x0, Tag=0x0
Mar 1 20:11:50.766: //43/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x15, Source Interface=0x84F61A50, Source Call Id=43,
Destination Call Id=42, Disposition=0x0, Tag=0x0
Mar 1 20:11:50.766: //42/45357FE18950/CCAPI/cc_generic_bridge_done:
Conference Id=0x15, Source Interface=0x84F61A50, Source Call Id=43,
Destination Call Id=42, Disposition=0x0, Tag=0x0
Mar 1 20:11:50.770: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWConnectionCreated: context=0x85196EBC
Mar 1 20:11:50.770: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessConnCreated: gConnTab=0x85196EBC, addr=172.16.35.16, port=5060, connid=7, transport=tcp
Mar 1 20:11:50.774: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnectionCreated: Moving connection=0x84F61054, connid=7state to pending
Mar 1 20:11:50.774: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWConnectionCreated: context=0x85196EBC
Mar 1 20:11:50.774: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessConnCreated: gConnTab=0x85196EBC, addr=172.16.35.16, port=5060, connid=7, transport=tcp
Mar 1 20:11:50.778: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportStopConnWaitTimer: Wait timer stopped for connection=0x84F61054,addr=172.16.35.16, port=5060
Mar 1 20:11:50.778: //42/45357FE18950/SIP/Transport/sipTransportPostInternalMsg: Posting Internal Msg type=0
Mar 1 20:11:50.778: //42/45357FE18950/SIP/Info/ccsip_lock_ccb: CCB locked; reference count=1
Mar 1 20:11:50.778: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessInternalMsg: context=0x856B760C
Mar 1 20:11:50.778: //42/45357FE18950/SIP/Info/ccsip_unlock_ccb: CCB unlocked; reference count=0
Mar 1 20:11:50.778: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x858277E8, addr=172.16.35.16, port=5060, connId=7 for TCP
Mar 1 20:11:50.778: //42/45357FE18950/SIP/Info/sentByeDisconnecting: Sent Bye Request, starting DisconnectTimer
Mar 1 20:11:50.778: //42/45357FE18950/SIP/State/sipSPIChangeState: 0x856B760C : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
Mar 1 20:11:50.782: //42/45357FE18950/CCAPI/ccCallDisconnect:
Cause Value=127, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=127)
Mar 1 20:11:50.782: //42/45357FE18950/CCAPI/ccCallDisconnect:
Cause Value=127, Call Entry(Responsed=TRUE, Cause Value=127)
Mar 1 20:11:50.782: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
Mar 1 20:11:50.786: //43/45357FE18950/CCAPI/ccCallDisconnect:
Cause Value=127, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Mar 1 20:11:50.786: //43/45357FE18950/CCAPI/ccCallDisconnect:
Cause Value=127, Call Entry(Responsed=TRUE, Cause Value=127)
Mar 1 20:11:50.786: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
Mar 1 20:11:50.786: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:skype.kris.local:5060;maddr=172.16.35.16;transport=Tcp;ms-opaque=073b03d74bdbcc11 SIP/2.0
Via: SIP/2.0/TCP 172.16.0.241;branch=z9hG4bK60885
From: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
To: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;epid=57E1FE531C;tag=cc38cd30e4
Date: Fri, 01 Mar 2002 20:10:57 GMT
Call-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 70
Timestamp: 1015013510
CSeq: 101 BYE
Content-Length: 0


Mar 1 20:11:50.790: //43/45357FE18950/SIP/Info/sipSPIValidateGtd: No rawMsg from CCAPI
Mar 1 20:11:50.790: //43/45357FE18950/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
Mar 1 20:11:50.790: //43/45357FE18950/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
Mar 1 20:11:50.794: //43/45357FE18950/SIP/Transport/sipSPISendBye: Sending BYE to the transport layer
Mar 1 20:11:50.798: //43/45357FE18950/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Mar 1 20:11:50.798: //43/45357FE18950/SIP/Transport/sipSPITransportSendMessage: msg=0x858277E8, addr=172.16.1.218, port=5060, sentBy_port=0, is_req=1, transport=2, switch=0, callBack=0x80F32ED8
Mar 1 20:11:50.798: //43/45357FE18950/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:50.798: //43/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:50.798: //43/45357FE18950/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x858277E8
Mar 1 20:11:50.798: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x858277E8, addr=172.16.1.218, port=5060, connId=6 for TCP
Mar 1 20:11:50.798: //43/45357FE18950/SIP/Info/sentByeDisconnecting: Sent Bye Request, starting DisconnectTimer
Mar 1 20:11:50.798: //43/45357FE18950/SIP/State/sipSPIChangeState: 0x856CA76C : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
Mar 1 20:11:50.802: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:win2012-04.valio.local:5060;maddr=172.16.1.218;transport=Tcp;ms-opaque=841957df6d23ece8 SIP/2.0
Via: SIP/2.0/TCP 172.16.0.241;branch=z9hG4bK61CCA
From: "kris test" <sip:35924910702@172.16.0.241>;tag=454A018-AD3
To: <sip:+35924910610@172.16.1.218>;tag=80ab175cce;epid=8379FDC2F7
Date: Fri, 01 Mar 2002 20:11:31 GMT
Call-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 70
Timestamp: 1015013510
CSeq: 103 BYE
Content-Length: 0

Mar 1 20:11:50.810: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x85196EBC
Mar 1 20:11:50.810: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.35.16, port=5060, connid=7, transport=tcp
Mar 1 20:11:50.814: //42/45357FE18950/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x856B760C
Mar 1 20:11:50.814: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
FROM: <sip:+35924910610@cisco.kris.local;user=phone>;tag=4549F98-14C8
TO: "kris test"<sip:+35924910702;ext=702@skype.kris.local;user=phone>;tag=cc38cd30e4;epid=57E1FE531C
CSEQ: 101 BYE
CALL-ID: 3e76def5-9e7e-4ef1-965c-3ca2c9f44475
VIA: SIP/2.0/TCP 172.16.0.241;branch=z9hG4bK60885
CONTENT-LENGTH: 0
SERVER: RTCC/6.0.0.0 MediationServer

Mar 1 20:11:50.814: //42/45357FE18950/SIP/Info/sipSPIIcpifUpdate: CallState: 3 Playout: 0 DiscTime:7271082 ConnTime 7265726
Mar 1 20:11:50.818: //42/45357FE18950/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x84F61A50, Tag=0x0, Call Id=42,
Call Entry(Disconnect Cause=127, Voice Class Cause Code=0, Retry Count=0)
Mar 1 20:11:50.818: //42/45357FE18950/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Mar 1 20:11:50.822: //42/45357FE18950/SIP/State/sipSPIChangeState: 0x856B760C : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
Mar 1 20:11:50.822: //42/45357FE18950/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x856B760C
State of The Call : STATE_DEAD
TCP Sockets Used : YES
Calling Number : 35924910702
Called Number : +35924910610
Source IP Address (Sig ): 172.16.0.241
Destn SIP Req Addr:Port : 172.16.35.16:5060
Destn SIP Resp Addr:Port : 172.16.35.16:49750
Destination Name : 172.16.35.16

Mar 1 20:11:50.822: //42/45357FE18950/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101
Source IP Address (Media): 172.16.0.241
Source IP Port (Media): 16732
Destn IP Address (Media): 172.16.35.16
Destn IP Port (Media): 54990
Orig Destn IP Address:Port (Media): 0.0.0.0:0

Mar 1 20:11:50.822: //42/45357FE18950/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 127
Disconnect Cause (SIP) : 200

Mar 1 20:11:50.822: //42/45357FE18950/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 2A
Mar 1 20:11:50.826: //42/45357FE18950/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
Mar 1 20:11:50.826: //42/45357FE18950/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x856B760C key=3e76def5-9e7e-4ef1-965c-3ca2c9f44475+35924910610
Mar 1 20:11:50.826: //42/45357FE18950/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
Mar 1 20:11:50.826: //42/45357FE18950/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x856B760C key=3e76def5-9e7e-4ef1-965c-3ca2c9f444754549F98-14C8
Mar 1 20:11:50.830: //42/45357FE18950/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Mar 1 20:11:50.830: //42/45357FE18950/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 856B760C
Mar 1 20:11:50.838: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x85196EBC
Mar 1 20:11:50.838: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.1.218, port=5060, connid=6, transport=tcp
Mar 1 20:11:50.842: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
FROM: "kris test"<sip:35924910702@172.16.0.241>;tag=454A018-AD3
TO: <sip:+35924910610@172.16.1.218>;tag=80ab175cce;epid=8379FDC2F7
CSEQ: 103 BYE
CALL-ID: 45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
VIA: SIP/2.0/TCP 172.16.0.241;branch=z9hG4bK61CCA
CONTENT-LENGTH: 0
SERVER: RTCC/6.0.0.0 MediationServer

Mar 1 20:11:50.842: //43/45357FE18950/SIP/Info/sipSPIIcpifUpdate: CallState: 3 Playout: 0 DiscTime:7271084 ConnTime 7265720
Mar 1 20:11:50.846: //43/45357FE18950/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x84F61A50, Tag=0x0, Call Id=43,
Call Entry(Disconnect Cause=127, Voice Class Cause Code=0, Retry Count=0)
Mar 1 20:11:50.846: //43/45357FE18950/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Mar 1 20:11:50.846: //43/45357FE18950/SIP/State/sipSPIChangeState: 0x856CA76C : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
Mar 1 20:11:50.850: //43/45357FE18950/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x856CA76C
State of The Call : STATE_DEAD
TCP Sockets Used : YES
Calling Number : 35924910702
Called Number : +35924910610
Source IP Address (Sig ): 172.16.0.241
Destn SIP Req Addr:Port : 172.16.1.218:5060
Destn SIP Resp Addr:Port : 172.16.1.218:5060
Destination Name : 172.16.1.218

Mar 1 20:11:50.850: //43/45357FE18950/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101
Source IP Address (Media): 172.16.0.241
Source IP Port (Media): 16502
Destn IP Address (Media): 172.16.1.218
Destn IP Port (Media): 55006
Orig Destn IP Address:Port (Media): 0.0.0.0:0

Mar 1 20:11:50.850: //43/45357FE18950/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 127
Disconnect Cause (SIP) : 200

Mar 1 20:11:50.850: //43/45357FE18950/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 2B
Mar 1 20:11:50.850: //43/45357FE18950/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.
Mar 1 20:11:50.854: //43/45357FE18950/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x856CA76C key=45486B1A-2C8711D6-8952D372-3AB19C5F@172.16.0.241
Mar 1 20:11:50.854: //43/45357FE18950/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Mar 1 20:11:50.858: //43/45357FE18950/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 856CA76Cno
Mar 1 20:11:57.914: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_sockerror_to_spi: Sending TCP Remote Closure to SPI, connid: 5
Mar 1 20:11:57.914: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWSocketException: context=0x00000000
Mar 1 20:11:57.914: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessSocketExceptions: gConnTab=0x85196EBC, addr=172.16.35.16, port=49744, connid=5, transport=tcp
Mar 1 20:11:57.918: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostCloseConnection: Posting TCP conn close for addr=172.16.35.16, port=49744, connid=5
Mar 1 20:11:57.918: //-1/xxxxxxxxxxxx/SIP/Transport/sipDeleteConnInstance: Deleted conn=0x85197D9C, connid=5, addr=172.16.35.16, port=49744, transport=tcp
Mar 1 20:11:57.922: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_purge_entry: Socket fd: 5 closed for connid 5 with address: 172.16.35.16, remote port: 49744
Mar 1 20:11:57.926: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
Mar 1 20:11:57.926: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.35.16, port=49756, connid=5, transport=tcp
Mar 1 20:11:57.926: //-1/xxxxxxxxxxxx/SIP/Transport/sipCreateConnInstance: Created new accptd conn=0x84F60F0C, connid=5, addr=172.16.35.16, port=49756, transport=tcp
Mar 1 20:11:57.926: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
Mar 1 20:11:57.926: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x85196EBC, addr=172.16.35.16, port=49756, connid=5, transport=tcp
Mar 1 20:11:57.930: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:cisco.kris.local SIP/2.0
FROM: <sip:skype.kris.local:5060;transport=Tcp;ms-opaque=073b03d74bdbcc11>;epid=57E1FE531C;tag=20dfa8629b
TO: <sip:cisco.kris.local>
CSEQ: 1137 OPTIONS
CALL-ID: 3d82bcbdb2a4459eb1d7bf4064033195
MAX-FORWARDS: 70
VIA: SIP/2.0/TCP 172.16.35.16:49756;branch=z9hG4bKdf571d9
CONTACT: <sip:skype.kris.local:5060;transport=Tcp;maddr=172.16.35.16>
CONTENT-LENGTH: 0
USER-AGENT: RTCC/6.0.0.0 MediationServer

Mar 1 20:11:57.930: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 172.16.35.16,Port 49756, Transport 2, SentBy Port 49756
Mar 1 20:11:57.934: //-1/6AE10D5B8954/SIP/State/sipSPIChangeState: 0x856B4FE0 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Mar 1 20:11:57.934: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.35.16,Port 49756, Transport 2, SentBy Port 5060
Mar 1 20:11:57.934: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
Mar 1 20:11:57.934: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 172.16.35.16,Port 49756, Transport 2, SentBy Port 49756
Mar 1 20:11:57.938: //-1/6AE10D5B8954/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
Mar 1 20:11:57.942: //-1/6AE10D5B8954/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
Mar 1 20:11:57.942: //-1/6AE10D5B8954/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
Mar 1 20:11:57.942: //-1/6AE10D5B8954/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
Mar 1 20:11:57.942: //-1/6AE10D5B8954/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
Mar 1 20:11:57.942: //-1/6AE10D5B8954/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
Mar 1 20:11:57.942: //-1/6AE10D5B8954/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
Mar 1 20:11:57.942: //-1/6AE10D5B8954/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
Mar 1 20:11:57.946: //-1/6AE10D5B8954/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.16.0.241
Mar 1 20:11:57.950: //-1/6AE10D5B8954/SIP/Transport/sipSPISendOptionsResponse: Sending OPTIONS Response to the transport layer
Mar 1 20:11:57.950: //-1/6AE10D5B8954/SIP/Transport/sipSPITransportSendMessage: msg=0x858276C8, addr=172.16.35.16, port=49756, sentBy_port=49756, is_req=0, transport=2, switch=0, callBack=0x80F34F14
Mar 1 20:11:57.950: //-1/6AE10D5B8954/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Mar 1 20:11:57.954: //-1/6AE10D5B8954/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Mar 1 20:11:57.954: //-1/6AE10D5B8954/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x858276C8 to default port=49756
Mar 1 20:11:57.954: //-1/6AE10D5B8954/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x858276C8
Mar 1 20:11:57.954: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x858276C8, addr=172.16.35.16, port=49756, connId=5 for TCP
Mar 1 20:11:57.954: //-1/6AE10D5B8954/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Mar 1 20:11:57.958: //-1/6AE10D5B8954/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 856B4FE0
Mar 1 20:11:57.962: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_sendmsg: Socket blocked data queued
Mar 1 20:11:57.962: //-1/xxxxxxxxxxxx/SIP/Info/sipTcpQueueSendData: Data queued length: 237
Mar 1 20:11:57.970: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:57.978: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:57.986: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:57.994: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:58.002: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:58.010: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_resend: Socket blocked requeue data
Mar 1 20:11:58.018: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.16.35.16:49756;branch=z9hG4bKdf571d9
From: <sip:skype.kris.local:5060;transport=Tcp;ms-opaque=073b03d74bdbcc11>;epid=57E1FE531C;tag=20dfa8629b
To: <sip:cisco.kris.local>;tag=4559678-2175
Date: Fri, 01 Mar 2002 20:11:57 GMT
Call-ID: 3d82bcbdb2a4459eb1d7bf4064033195
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1137 OPTIONS
Supported: 100rel,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Accept: applicatdebug ccsip allion/sdp
Allow-Events: telephone-event
Content-Length: 167
Content-Type: application/sdp

v=0
o=CiscoSystemsSIP-GW-UserAgent 8307 5181 IN IP4 172.16.0.241
s=SIP Call
c=IN IP4 172.16.0.241
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 172.16.0.241

Labels:
0 Helpful
1 Reply 1

kristiyan_n
Level 1
Level 1

‎10-03-2016 05:51 AM

I figured it out! I had to set dtmf-relay rtp-nte, 

Thank you for the tremendous help, Cisco guys!

0 Helpful
Customers Also Viewed These Support Documents