02-19-2013 01:43 AM - edited 03-16-2019 03:48 PM
Hi
Outbound calls are disconnecting when called party answers the phone. I have pasted the debug below. Would appreciate any help in understanding the issue. Thank you for your time
*Feb 19 09:07:44.910: //-1/A84612B7BD8D/CCAPI/cc_api_call_setup_ind_common:
Interface=0x7082DEB4, Call Info(
Calling Number=2169301,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=90097339982779(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=7300, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=30842
*Feb 19 09:07:44.910: //-1/A84612B7BD8D/CCAPI/ccCheckClipClir:
In: Calling Number=2169301(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Feb 19 09:07:44.910: //-1/A84612B7BD8D/CCAPI/ccCheckClipClir:
Out: Calling Number=2169301(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Feb 19 09:07:44.910: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Feb 19 09:07:44.910: :cc_get_feature_vsa malloc success
*Feb 19 09:07:44.910: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Feb 19 09:07:44.910: cc_get_feature_vsa count is 1
*Feb 19 09:07:44.910: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Feb 19 09:07:44.910: :FEATURE_VSA attributes are: feature_name:0,feature_time:1885860640,feature_id:649
*Feb 19 09:07:44.910: //30842/A84612B7BD8D/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=2169301(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=90097339982779(TON=Unknown, NPI=Unknown))
*Feb 19 09:07:44.910: //30842/A84612B7BD8D/CCAPI/cc_process_call_setup_ind:
Event=0x68032D20
*Feb 19 09:07:44.910: //30842/A84612B7BD8D/CCAPI/ccCallSetContext:
Context=0x7128125C
*Feb 19 09:07:44.910: //30842/A84612B7BD8D/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 30842 with tag 7300 to app "_ManagedAppProcess_Default"
*Feb 19 09:07:44.910: //30842/A84612B7BD8D/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
*Feb 19 09:07:44.914: //30842/A84612B7BD8D/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=9, Params=0x7127D8EC, Progress Indication=NULL(0)
*Feb 19 09:07:44.914: //30842/A84612B7BD8D/CCAPI/ccCheckClipClir:
In: Calling Number=2169301(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Feb 19 09:07:44.914: //30842/A84612B7BD8D/CCAPI/ccCheckClipClir:
Out: Calling Number=2169301(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Feb 19 09:07:44.914: //30842/A84612B7BD8D/CCAPI/ccCallSetupRequest:
Destination Pattern=9T, Called Number=0097339982779, Digit Strip=FALSE
*Feb 19 09:07:44.914: //30842/A84612B7BD8D/CCAPI/ccCallSetupRequest:
Calling Number=2169301(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=0097339982779(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=Aslam Shaik
Account Number=2169301, Final Destination Flag=TRUE,
Guid=A84612B7-79AA-11E2-BD8D-F6CECD79320E, Outgoing Dial-peer=9
*Feb 19 09:07:44.914: //30842/A84612B7BD8D/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=2169301
----- ccCallInfo IE subfields -----
cisco-ani=2169301
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=0097339982779
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
*Feb 19 09:07:44.914: //30842/A84612B7BD8D/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x7082DEB4, Interface Type=3, Destination=, Mode=0x0,
Call Params(Calling Number=2169301,(Calling Name=Aslam Shaik)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=0097339982779(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=9, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Feb 19 09:07:44.914: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Feb 19 09:07:44.914: :cc_get_feature_vsa malloc success
*Feb 19 09:07:44.914: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Feb 19 09:07:44.914: cc_get_feature_vsa count is 2
*Feb 19 09:07:44.914: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*Feb 19 09:07:44.914: :FEATURE_VSA attributes are: feature_name:0,feature_time:1885860424,feature_id:650
*Feb 19 09:07:44.914: //30843/A84612B7BD8D/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
*Feb 19 09:07:44.914: //30843/A84612B7BD8D/CCAPI/ccCallSetContext:
Context=0x7127D89C
*Feb 19 09:07:44.914: //30842/A84612B7BD8D/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=9
*Feb 19 09:07:44.914: //30843/A84612B7BD8D/CCAPI/cc_api_call_proceeding:
Interface=0x7082DEB4, Progress Indication=NULL(0)
*Feb 19 09:07:49.390: //30843/A84612B7BD8D/CCAPI/cc_api_call_alert:
Interface=0x7082DEB4, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
*Feb 19 09:07:49.390: //30843/A84612B7BD8D/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
*Feb 19 09:07:49.390: //30842/A84612B7BD8D/CCAPI/ccCallAlert:
Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
*Feb 19 09:07:49.390: //30842/A84612B7BD8D/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, AlertSent=TRUE)
*Feb 19 09:07:49.390: //30842/A84612B7BD8D/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=30842
*Feb 19 09:07:56.990: //30843/A84612B7BD8D/CCAPI/cc_api_caps_ind:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=30843,
Caps(Codec=0x2, Fax Rate=0x2, Vad=0x2,
Modem=0x0, Codec Bytes=160, Signal Type=2)
*Feb 19 09:07:56.990: //30843/A84612B7BD8D/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
Playout Max=250(ms), Fax Nom=300(ms))
*Feb 19 09:07:56.990: //30842/A84612B7BD8D/CCAPI/cc_api_caps_ack:
Destination Interface=0x0, Destination Call Id=30843, Source Call Id=30842,
Caps(Codec=g711alaw(0x2), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=1812)
*Feb 19 09:07:56.990: //30843/A84612B7BD8D/CCAPI/cc_api_event_indication:
Event=141, Call Id=30843
*Feb 19 09:07:56.990: //30843/A84612B7BD8D/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
*Feb 19 09:07:56.990: //30843/A84612B7BD8D/CCAPI/cc_api_call_connected:
Interface=0x7082DEB4, Data Bitmask=0x1, Progress Indication=NULL(0),
Connection Handle=0
*Feb 19 09:07:56.990: //30843/A84612B7BD8D/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
*Feb 19 09:07:56.990: //30842/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
(confID=0x66CE2948, callID1=0x787A, callID2=0x787B, tag=0x0)
*Feb 19 09:07:56.990: //30842/A84612B7BD8D/CCAPI/ccConferenceCreate:
Conference Id=0x66CE2948, Call Id1=30842, Call Id2=30843, Tag=0x0
*Feb 19 09:07:56.990: //30842/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
*Feb 19 09:07:56.990: ccConferenceCreate: ret1=0, codecMask1=2, bytes1=160, negot1=0, dtmf1=6
ret2=0, codecMask2=2, bytes2=160, negot2=1, dtmf2=3
*Feb 19 09:07:56.990: //30842/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
delay media to slow start case, codec negotation is not done
*Feb 19 09:07:56.990: //30842/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
*Feb 19 09:07:56.990: cc_api_get_xcode_stream : 4369
*Feb 19 09:07:56.990: //30842/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
Conference Id=0xB3, Source Interface=0x7082DEB4, Source Call Id=30842,
Destination Call Id=30843, Disposition=0x0, Tag=0x0
*Feb 19 09:07:56.990: //30843/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
*Feb 19 09:07:56.990: cc_api_get_xcode_stream : 4369
*Feb 19 09:07:56.990: //30843/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
Conference Id=0xB3, Source Interface=0x7082DEB4, Source Call Id=30843,
Destination Call Id=30842, Disposition=0x0, Tag=0x0
*Feb 19 09:07:56.990: //30842/A84612B7BD8D/CCAPI/cc_generic_bridge_done:
Conference Id=0xB3, Source Interface=0x7082DEB4, Source Call Id=30843,
Destination Call Id=30842, Disposition=0x0, Tag=0x0
*Feb 19 09:07:56.990: //30842/A84612B7BD8D/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0xB3, Destination Call Id=30843)
*Feb 19 09:07:56.990: //30843/A84612B7BD8D/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0xB3, Destination Call Id=30842)
*Feb 19 09:07:56.990: //30842/A84612B7BD8D/CCAPI/cc_process_notify_bridge_done:
Conference Id=0xB3, Call Id1=30842, Call Id2=30843
*Feb 19 09:07:56.994: //30842/A84612B7BD8D/CCAPI/ccCallConnect:
Progress Indication=NULL(0), Data Bitmask=0x1
*Feb 19 09:07:56.994: //30842/A84612B7BD8D/CCAPI/ccCallConnect:
Call Entry(Connected=TRUE, Responsed=TRUE)
*Feb 19 09:07:56.994: //30842/A84612B7BD8D/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=30842
*Feb 19 09:07:57.170: //30842/A84612B7BD8D/CCAPI/cc_api_call_disconnected:
Cause Value=65, Interface=0x7082DEB4, Call Id=30842
*Feb 19 09:07:57.170: //30842/A84612B7BD8D/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=65, Retry Count=0)
*Feb 19 09:07:57.170: //30843/A84612B7BD8D/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=FALSE, Tone=Null,
Tone Direction=Sum Network, Params=0x0, Call Id=30843
*Feb 19 09:07:57.170: //30842/A84612B7BD8D/CCAPI/ccConferenceDestroy:
Conference Id=0xB3, Tag=0x0
*Feb 19 09:07:57.170: //30842/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
Conference Id=0xB3, Source Interface=0x7082DEB4, Source Call Id=30842,
Destination Call Id=30843, Disposition=0x0, Tag=0x0
*Feb 19 09:07:57.170: //30843/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
Conference Id=0xB3, Source Interface=0x7082DEB4, Source Call Id=30843,
Destination Call Id=30842, Disposition=0x0, Tag=0x0
*Feb 19 09:07:57.174: //30842/A84612B7BD8D/CCAPI/cc_generic_bridge_done:
Conference Id=0xB3, Source Interface=0x7082DEB4, Source Call Id=30843,
Destination Call Id=30842, Disposition=0x0, Tag=0x0
*Feb 19 09:07:57.174: //30842/A84612B7BD8D/CCAPI/ccCallDisconnect:
Cause Value=65, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=65)
*Feb 19 09:07:57.174: //30842/A84612B7BD8D/CCAPI/ccCallDisconnect:
Cause Value=65, Call Entry(Responsed=TRUE, Cause Value=65)
*Feb 19 09:07:57.174: //30843/A84612B7BD8D/CCAPI/ccCallDisconnect:
Cause Value=65, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Feb 19 09:07:57.174: //30843/A84612B7BD8D/CCAPI/ccCallDisconnect:
Cause Value=65, Call Entry(Responsed=TRUE, Cause Value=65)
*Feb 19 09:07:57.226: //30843/A84612B7BD8D/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7082DEB4, Tag=0x0, Call Id=30843,
Call Entry(Disconnect Cause=65, Voice Class Cause Code=0, Retry Count=0)
*Feb 19 09:07:57.226: //30843/A84612B7BD8D/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Feb 19 09:07:57.226: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*Feb 19 09:07:57.226: :cc_free_feature_vsa freeing 7067F240
*Feb 19 09:07:57.226: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*Feb 19 09:07:57.226: vsacount in free is 1
*Feb 19 09:07:57.234: //30842/A84612B7BD8D/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7082DEB4, Tag=0x0, Call Id=30842,
Call Entry(Disconnect Cause=65, Voice Class Cause Code=0, Retry Count=0)
*Feb 19 09:07:57.234: //30842/A84612B7BD8D/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Feb 19 09:07:57.234: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*Feb 19 09:07:57.234: :cc_free_feature_vsa freeing 7067F318
*Feb 19 09:07:57.234: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*Feb 19 09:07:57.234: vsacount in free is 0
*Feb 19 09:08:15.770: //30845/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Call Entry Is Not Found
*Feb 19 09:08:15.770: //-1/BAAA539DBD94/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=97339982779
----- ccCallInfo IE subfields -----
cisco-ani=97339982779
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=2169301
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
Solved! Go to Solution.
02-19-2013 05:37 AM
G711ulaw is certainly in the attributes..Looking at the M-line
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
CUBE is offering
18=G729
0=PCMU---------G711u
8=PCMA-----G711a
And your provider certainly supports G711ulaw and alaw
This is the SDP in the response received from your ITSP
m=audio 37372 RTP/AVP 8 0 18 4 2 98 99 97
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:4 G723/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:98 G726-40/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
a=fmtp:18 annexb=yes
Please rate all useful posts
"opportunity is a haughty goddess who waste no time with those who are unprepared"
02-19-2013 03:12 AM
You need to mention products and version used, complete call flow, configuration, etc.
02-19-2013 03:29 AM
As paolo suggested, please describe your call flow and the components involved..
Also i see call is disconnected by the originating gateway with a cause code of 65..Which is most times codec related...
So we need to see what capabilities are exchanged...
You can send a debug h225 asn1 and debug h245 asn1 along with your sh run and call flow description
Please rate all useful posts
"opportunity is a haughty goddess who waste no time with those who are unprepared"
02-19-2013 04:41 AM
Thank you for taking time to review this. This VG is connected to ISP via SIP trunk. I have also pasted below debug ccsip all. I will post the other information requested by aokanlawon also shortly.
*Feb 19 11:10:34.521: //31226/BDD4BC8FBF32/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 79FA
*Feb 19 11:10:34.521: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[31239] removed.
*Feb 19 11:10:34.521: //31226/BDD4BC8FBF32/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 19 11:10:34.521: //31226/BDD4BC8FBF32/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x7078E564 key=isbcthet427pekuth2ahe2utpc7utsu2tpuh@SoftX3000
*Feb 19 11:10:34.521: //31226/BDD4BC8FBF32/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 19 11:10:34.521: //31226/BDD4BC8FBF32/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 19 11:10:34.521: //31226/BDD4BC8FBF32/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 7078E564
*Feb 19 11:10:42.365: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:10:42.365: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 19 11:10:42.365: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x7095EC90, addr=10.104.160.30, port=41357, connid=1, transport=TCP
*Feb 19 11:10:42.365: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:90097339982779@10.106.78.7:5060 SIP/2.0
Date: Tue, 19 Feb 2013 11:03:45 GMT
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
From: "Aslam Shaik" <2169301>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-477193402169301>
Allow-Events: presence
P-Asserted-Identity: "Aslam Shaik" <2169301>2169301>
Supported: timer,resource-priority,replaces
Min-SE: 1800
Remote-Party-ID: "Aslam Shaik" <2169301>;party=calling;screen=yes;privacy=off2169301>
Content-Length: 0
User-Agent: Cisco-CUCM7.0
To: <90097339982779>90097339982779>
Contact: <2169301>2169301>
Expires: 180
Call-ID: 6014400-12315c11-adf1-1ea0680a@10.104.160.30
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeaa03332dc41
CSeq: 101 INVITE
Session-Expires: 1800
Max-Forwards: 70
*Feb 19 11:10:42.365: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:10:42.369: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x70782DB8) with key=[31241] to table
*Feb 19 11:10:42.369: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.104.160.30,Port 41357, Transport 2, SentBy Port 5060
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70782DB8 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 19 11:10:42.369: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.104.160.30,Port 41357, Transport 2, SentBy Port 5060
*Feb 19 11:10:42.369: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 19 11:10:42.369: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.104.160.30,Port 41357, Transport 2, SentBy Port 5060
*Feb 19 11:10:42.369: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckIpip: VOIP dialpeer (peer=0x6652B9AC) found for sip_user: 90097339982779
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPISetInfoFromRpid: Received current remote name: Aslam Shaik, current remote number: 2169301
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPISetInfoFromRpid: Received ;screen=yes ;privacy=off -> Setting Octet3A 0x81, extended_privacy 0x00
*Feb 19 11:10:42.369: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x70782DB8 key=6014400-12315c11-adf1-1ea0680a@10.104.160.3090097339982779
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on carrier id
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 90097339982779
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on destination pattern: 2169301
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name Aslam Shaik, number 2169301, Calling oct3 0x00, oct_3a 0x81, Called number 90097339982779
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIGetCallConfig: Peer tag 7300 matched for incoming call
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIContinueNewMsgInvite: Calling name Aslam Shaik, number 2169301, Calling oct3 0x00, oct_3a 0x81, ext_priv 0x00, Called number 90097339982779, oct3 0x00
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE
*Feb 19 11:10:42.369: //-1/D5950F0DBF34/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPINegotiateSessionExpires:
Session-Expires value: 1800 refresher: 3
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPINegotiateSessionExpires: Min-SE Header: 1800
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Error/sipSPIProcessCallInfoHeader: Call-Info header with for Unsolicited Notify Absent,Disabling Unsolicited Notifies
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPIGetContentSDP: No SDP found in inbound container
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPIDoQoSNegotiation: No SDP body
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 31228, sdp 0x66CF34D4 channels 0x70783E88
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/copy_channels:
callId 31228 size 0 ptr 0x706BD774)
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Delayed media case...creating new stream
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.106.78.7
*Feb 19 11:10:42.369: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 17372 for stream 1
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Reserving rtp port for stream 1, src_port=17372
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPIAddBillingInfoToCcb: sipCallId for billing records = 6014400-12315c11-adf1-1ea0680a@10.104.160.30
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPI_ipip_store_channel_info: Store channelInfo in CallInfo
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPI_ipip_store_channel_info: negotiated dtmf not available, using dialpeer config dtmf = 6
*Feb 19 11:10:42.369: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD passed.
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/ccsip_set_bearer_capability:
Bearer Capability: Speech (0x00)
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPIContinueNewMsgInvite: ccsip_api_call_setup_ind returned: SIP_SUCCESS_DELAYED_MEDIA
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 79FC to table
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: msg=0x6800EE4C, addr=10.104.160.30, port=41357, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=0x00000000
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:10:42.369: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x6800EE4C, addr=10.104.160.30, port=41357, connId=1 for TCP
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70782DB8 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_RECD_INVITE, SUBSTATE_NONE)
*Feb 19 11:10:42.369: //31228/D5950F0DBF34/SIP/Info/sipSPIProcessContactInfo: Previous Hop 10.104.160.30:5060
*Feb 19 11:10:42.373: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeaa03332dc41
From: "Aslam Shaik" <2169301>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-477193402169301>
To: <90097339982779>90097339982779>
Date: Tue, 19 Feb 2013 11:10:42 GMT
Call-ID: 6014400-12315c11-adf1-1ea0680a@10.104.160.30
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow-Events: telephone-event
Content-Length: 0
*Feb 19 11:10:42.373: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
*Feb 19 11:10:42.373: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x70795398) with key=[31242] to table
*Feb 19 11:10:42.373: //31229/000000000000/SIP/State/sipSPIChangeState: 0x70795398 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 19 11:10:42.373: //31229/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200
*Feb 19 11:10:42.373: //31229/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: NULL peer_channel - Delayed Media: peer_channels = 0x0, peer_param = 0x71190624, callID= 31229, peer_callID = 31228
*Feb 19 11:10:42.373: //31229/000000000000/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-SIP
*Feb 19 11:10:42.373: //31229/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 400000
*Feb 19 11:10:42.373: //31229/D5950F0DBF34/SIP/Info/sipSPIGetOutboundHostAndDestHost: CCSIP: target_host : 10.200.7.157 target_port : 5060
*Feb 19 11:10:42.373: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*Feb 19 11:10:42.373: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
*Feb 19 11:10:42.373: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 147)
*Feb 19 11:10:42.373: //31228/D5950F0DBF34/SIP/Info/ccsip_event_handler:
ccsip_event_handler: peer ID 31229 chans 0x712DCD68 event 147 flags 0x40201C 0x300 0x201 data 0x712DCD68
*Feb 19 11:10:42.373: //31228/D5950F0DBF34/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_SET_MODE: peer ID 31229 chans 0x712DCD68 event 147 flags 0x40201C 0x300 0x201 data 0x712DCD68, type = 1
*Feb 19 11:10:42.373: //31228/D5950F0DBF34/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-SIP
*Feb 19 11:10:42.373: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
*Feb 19 11:10:42.373: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 3
*Feb 19 11:10:42.373: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
*Feb 19 11:10:42.373: //31229/D5950F0DBF34/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 79FD to table
*Feb 19 11:10:42.373: //31229/D5950F0DBF34/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0
*Feb 19 11:10:42.373: //31229/D5950F0DBF34/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Feb 19 11:10:42.373: //31229/D5950F0DBF34/SIP/Info/sipSPIGetCallConfig: Not using Voice Class Codec
*Feb 19 11:10:42.373: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
callid 31229, channels 0x0 caps 0x685A4B00
*Feb 19 11:10:42.373: //31229/D5950F0DBF34/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
*Feb 19 11:10:42.373: //31229/D5950F0DBF34/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Info/act_idle_continue_call_setup:
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x70795398 key=D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
*Feb 19 11:10:42.377: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 19 11:10:42.377: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 19 11:10:42.377: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Event/sipSPICreateRpid: Received Octet3A=0x81 -> Setting ;screen=yes ;privacy=off
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Info/sipSPISendInvite: Associated container=0x7117C820 to Invite
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: msg=0x70C7F080, addr=10.200.7.157, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x615E6EC8
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x70C7F080
*Feb 19 11:10:42.377: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70C7F080, addr=10.200.7.157, port=5060, connId=1 for UDP
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE
*Feb 19 11:10:42.377: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued.
*Feb 19 11:10:42.377: //31229/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70795398 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE)
*Feb 19 11:10:42.377: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:0097339982779@10.200.7.157:5060 SIP/2.0
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bK3F815E6
Remote-Party-ID: "Aslam Shaik" <2169301>;party=calling;screen=yes;privacy=off2169301>
From: "Aslam Shaik" <2169301>;tag=248B1A04-13842169301>
To: <0097339982779>0097339982779>
Date: Tue, 19 Feb 2013 11:10:42 GMT
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
Supported: 100rel,timer,resource-priority,replaces
Min-SE: 1800
Cisco-Guid: 3583315725-2042302946-3207919310-3447271950
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1361272242
Contact: <2169301>2169301>
Call-Info: <10.66.7.218:5060>;method="NOTIFY;Event=telephone-event;Duration=2000"10.66.7.218:5060>
Expires: 180
Allow-Events: telephone-event
Max-Forwards: 69
Content-Length: 0
*Feb 19 11:10:42.389: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 19 11:10:42.389: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:10:42.389: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 19 11:10:42.389: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:10:42.389: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bK3F815E6
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
From: "Aslam Shaik"<2169301>;tag=248B1A04-13842169301>
To: <0097339982779>0097339982779>
CSeq: 101 INVITE
Content-Length: 0
*Feb 19 11:10:42.389: //31229/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70795398 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:10.66.7.218:5060 SIP/2.0
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKo2kofkt7hu47dupoosc7ausefT17526
Call-ID: isbcoc2au4b7s4oco2tobse77otchdeeepk7@SoftX3000
From: <10.66.7.218:5060>;tag=sbc08032sehe4do10.66.7.218:5060>
To: <10.66.7.218>10.66.7.218>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x7077BF84) with key=[31243] to table
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 19 11:10:42.645: //-1/D5BF2BC9BF3A/SIP/State/sipSPIChangeState: 0x7077BF84 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.66.7.218
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/State/sipSPIChangeState: 0x7077BF84 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x7077BF84 key=isbcoc2au4b7s4oco2tobse77otchdeeepk7@SoftX3000
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 79FE to table
*Feb 19 11:10:42.645: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Info/sipSPISendOptionsResponse: Associated container=0x7117DA68 to Options Response
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Transport/sipSPITransportSendMessage: msg=0x70861A28, addr=10.200.7.157, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x615E9E98
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:10:42.645: //31230/D5BF2BC9BF3A/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:10:42.649: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70861A28, addr=10.200.7.157, port=5060, connId=0 for UDP
*Feb 19 11:10:42.649: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKo2kofkt7hu47dupoosc7ausefT17526
From: <10.66.7.218:5060>;tag=sbc08032sehe4do10.66.7.218:5060>
To: <10.66.7.218>;tag=248B1B10-173010.66.7.218>
Date: Tue, 19 Feb 2013 11:10:42 GMT
Call-ID: isbcoc2au4b7s4oco2tobse77otchdeeepk7@SoftX3000
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 OPTIONS
Supported: 100rel,resource-priority,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Content-Type: application/sdp
Content-Length: 164
v=0
o=CiscoSystemsSIP-GW-UserAgent 2842 1907 IN IP4 10.106.78.7
s=SIP Call
c=IN IP4 10.66.7.218
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 10.66.7.218
*Feb 19 11:10:47.421: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 19 11:10:47.421: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:10:47.421: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 19 11:10:47.421: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bK3F815E6
Record-Route: <10.200.7.157:5060>10.200.7.157:5060>
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
From: "Aslam Shaik"<2169301>;tag=248B1A04-13842169301>
To: <0097339982779>;tag=sbc0804h4bsb2sa-CC-300097339982779>
CSeq: 101 INVITE
Contact: <0097339982779>0097339982779>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Require: 100rel
RSeq: 1
Content-Length: 371
Content-Type: application/sdp
v=0
o=- 28471578 28471578 IN IP4 10.200.7.157
s=SBC call
c=IN IP4 10.200.7.157
t=0 0
m=audio 37372 RTP/AVP 8 0 18 4 2 98 99 97
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:4 G723/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:98 G726-40/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
a=fmtp:18 annexb=yes
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Info/ccsip_api_call_alert: SDP in 180 will be ignored if present: No early media cut through
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Error/sipSPIProcessCallInfoHeader: Call-Info header with for Unsolicited Notify Absent,Disabling Unsolicited Notifies
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Info/ccsip_api_call_alert: SDP Body either absent or ignored in 180 RINGING:- will wait for 200 OK to do negotiation.
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD passed.
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_MEDIA_EVENT
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Info/HandleSIP1xxRinging: ccsip_api_call_alert returned: SIP_SUCCESS
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70795398 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70795398 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (SIP_STATE_SEND_PRACK, SUBSTATE_NONE)
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 29
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Info/act_handle_app_media_event: method = 105 state = 23
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Info/act_handle_app_media_event: Received media sip event SIP_REQUEST_ANSWER
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70795398 : State change from (SIP_STATE_SEND_PRACK, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddRouteHeaders: status = TRUE Route: <10.200.7.157:5060>10.200.7.157:5060>
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: msg=0x70861A28, addr=10.200.7.157, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x615E7910
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x70861A28
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70861A28, addr=10.200.7.157, port=5060, connId=1 for UDP
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Info/sentPrack: Sent Prack Request, starting PrackTimer
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70795398 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*Feb 19 11:10:47.425: //31229/D5950F0DBF34/SIP/Info/sact_app_media_event_send_request: Transaction active. Facilities will be queued.
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_ALERTING
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_MEDIA_EVENT
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:0097339982779@10.200.7.157:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bK3F937F
From: "Aslam Shaik" <2169301>;tag=248B1A04-13842169301>
To: <0097339982779>;tag=sbc0804h4bsb2sa-CC-300097339982779>
Date: Tue, 19 Feb 2013 11:10:42 GMT
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
CSeq: 102 PRACK
RAck: 1 101 INVITE
Route: <10.200.7.157:5060>10.200.7.157:5060>
Max-Forwards: 70
Content-Length: 0
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 5
*Feb 19 11:10:47.425: //31228/D5950F0DBF34/SIP/Info/sipSPIUaddCcbToUASRespTable: ****Adding to UAS Response table.
*Feb 19 11:10:47.425: //31228/D5950F0DBF34/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x70782DB8 key=6014400-12315c11-adf1-1ea0680a@10.104.160.30248B2DBC-3A7
*Feb 19 11:10:47.425: //31228/D5950F0DBF34/SIP/Info/ccsip_api_update_allowed: UPDATE(1) allowed
*Feb 19 11:10:47.425: //31228/D5950F0DBF34/SIP/Event/sipSPICreateRpid: Received Octet3A=0x00 -> Setting ;screen=no ;privacy=off
*Feb 19 11:10:47.425: //31228/D5950F0DBF34/SIP/Transport/sipSPISendInviteResponse: Sending 180 Response to the Transport Layer
*Feb 19 11:10:47.425: //31228/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: msg=0x70861A28, addr=10.104.160.30, port=41357, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=0x615E77D0
*Feb 19 11:10:47.425: //31228/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:10:47.425: //31228/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70861A28, addr=10.104.160.30, port=41357, connId=1 for TCP
*Feb 19 11:10:47.425: //31228/D5950F0DBF34/SIP/Info/sentInviteResponse18x: Sent a 18x Response
*Feb 19 11:10:47.425: //31228/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70782DB8 : State change from (STATE_RECD_INVITE, SUBSTATE_NONE) to (STATE_SENT_ALERTING, SUBSTATE_NONE)
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 29
*Feb 19 11:10:47.425: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 180 Ringing
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeaa03332dc41
From: "Aslam Shaik" <2169301>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-477193402169301>
To: <90097339982779>;tag=248B2DBC-3A790097339982779>
Date: Tue, 19 Feb 2013 11:10:42 GMT
Call-ID: 6014400-12315c11-adf1-1ea0680a@10.104.160.30
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: <0097339982779>;party=called;screen=no;privacy=off0097339982779>
Contact: <0097339982779>0097339982779>
Content-Length: 0
*Feb 19 11:10:47.477: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 19 11:10:47.477: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:10:47.477: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 19 11:10:47.477: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:10:47.481: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bK3F937F
Record-Route: <10.200.7.157:5060>10.200.7.157:5060>
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
From: "Aslam Shaik"<2169301>;tag=248B1A04-13842169301>
To: <0097339982779>;tag=sbc0804h4bsb2sa-CC-300097339982779>
CSeq: 102 PRACK
Content-Length: 0
*Feb 19 11:10:47.481: //31229/D5950F0DBF34/SIP/Info/act_recdproc_new_message_response: Transaction Complete. Lock on Facilities released.
*Feb 19 11:10:47.481: //31229/D5950F0DBF34/SIP/Info/ccsip_api_update_allowed: UPDATE(2) allowed
*Feb 19 11:10:54.581: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 19 11:10:54.581: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:10:54.581: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 19 11:10:54.581: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:10:54.581: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bK3F815E6
Record-Route: <10.200.7.157:5060>10.200.7.157:5060>
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
From: "Aslam Shaik"<2169301>;tag=248B1A04-13842169301>
To: <0097339982779>;tag=sbc0804h4bsb2sa-CC-300097339982779>
CSeq: 101 INVITE
Contact: <0097339982779>0097339982779>
Content-Length: 371
Content-Type: application/sdp
v=0
o=- 28471578 28471579 IN IP4 10.200.7.157
s=SBC call
c=IN IP4 10.200.7.157
t=0 0
m=audio 37372 RTP/AVP 8 0 18 4 2 98 99 97
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:4 G723/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:98 G726-40/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
a=fmtp:18 annexb=yes
*Feb 19 11:10:54.581: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 19 11:10:54.581: //31229/D5950F0DBF34/SIP/Info/sipSPIhandle200OKInvite: Transaction active. Facilities will be queued.
*Feb 19 11:10:54.581: //31229/D5950F0DBF34/SIP/Info/sipSPIhandle200OKInvite: *** This ccb is the parent
*Feb 19 11:10:54.581: //31228/D5950F0DBF34/SIP/Info/sipSPIUupdateCcCallIds: Old src/dest ccCallids: -1/-1, new src/dest ccCallids: 31228/31229
*Feb 19 11:10:54.581: //31228/D5950F0DBF34/SIP/Info/sipSPIUupdateCcCallIds: Old streamcallid=-1, new streamcallid=31228
*Feb 19 11:10:54.581: //31229/D5950F0DBF34/SIP/Error/sipSPIProcessCallInfoHeader: Call-Info header with for Unsolicited Notify Absent,Disabling Unsolicited Notifies
*Feb 19 11:10:54.581: //31229/D5950F0DBF34/SIP/Info/sipSPICompareRespMediaInfo: No Comparsion needed as 18x response SDP is either absent or ignored
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIDoAudioNegotiation: Codec (g711alaw) Negotiation Successful on Static Payload for m-line 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIDoDTMFRelayNegotiation: Requested DTMF-RELAY option(s) not found in Preferred DTMF-RELAY option list!
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIStreamTypeAndDtmfRelay: DTMF Relay mode: Inband Voice
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/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
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1
payload_type=8, codec_bytes=160, codec=g711alaw, dtmf_relay=inband-voice
stream_type=voice-only (0), dest_ip_address=10.200.7.157, dest_port=37372
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING)
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Media/sipSPIUpdCallWithSdpInfo:
Preferred Codec : g711alaw, bytes :160
Preferred DTMF relay : h245-alphanumeric
Preferred NTE payload : 101
Early Media : No
Delayed Media : Yes
Bridge Done : No
New Media : No
DSP DNLD Reqd : No
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.66.7.218
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 31229 peer 31228 flags 0x400001
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 31229, sdp 0x68583358 channels 0x70796468
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/copy_channels:
callId 31229 size 0 ptr 0x66CE6898)
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 8 mline 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711alaw
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/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
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 6 ptype 8 time 20, bytes 160 as channel 0 mline 1 ss 0 10.200.7.157:37372
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 0 mline 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711ulaw
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 18 mline 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Media/sipSPISelectCodecVersion: Codec (g729br8) is not in preferred list
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: An exact codec match not configured, using interoperable codec g729r8 pre-ietf
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g729r8 pre-ietf
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 4 mline 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Media/sipSPISelectCodecVersion: Codec (No Codec ) is not in preferred list
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: An exact codec match not configured, using interoperable codec g729r8 pre-ietf
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g729r8 pre-ietf
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 2 mline 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g726r32
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 98 mline 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 99 mline 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 97 mline 1
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPI_ipip_report_media_to_peer:
Report initial call media
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/ccsip_get_rtcp_session_parameters: CURRENT VALUES: stream_callid=-1, current_seq_num=0xAFF
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/ccsip_get_rtcp_session_parameters: NEW VALUES: stream_callid=-1, current_seq_num=0x0
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/ccsip_do_caps_ind: Load DSP with codec: g711alaw, Bytes=160
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Media/sipSPISetStreamInfo: 0 Active Streams
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Media/sipSPISetStreamInfo: Number of active streams is zero (0)!
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Media/sipSPISetStreamInfo:
caps.stream_count=0,caps.stream[0].stream_type=0xFFFF, caps.stream_list.xmitFunc=
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Media/sipSPISetStreamInfo: ??unknown??, caps.stream_list.context=
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Media/sipSPISetStreamInfo: 0x0 (gccb)
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPISetDTMFRelayMode: Set DSP for dtmf-relay = CC_CAP_DTMF_RELAY_INBAND_VOICE_AND_OOB
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sip_set_modem_caps: Preferred (or the one that came from DSM) modem relay=0, from CLI config=0
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sip_set_modem_caps: Disabling Modem Relay...
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sip_set_modem_caps: Negotiation already Done. Set negotiated Modem caps and generate SDP Xcap list
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sip_set_modem_caps: Modem Relay & Passthru both disabled
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/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
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_caps_ind:
destCallID=31228, srcCallID=31229,
peer_ccb->call_info.currentLocalName=,
peer_ccb->call_info.currentRemoteName=,
ccb->call_info.currentLocalName=,
ccb->call_info.currentRemoteName=Aslam Shaik
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/ccsip_caps_ind: Negotiation not done yet:: Load DSP with preferred codec : g711alaw, Bytes=160, payload: 8
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/ccsip_caps_ind: Set forking flag to 0x0
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sip_set_modem_caps: Preferred (or the one that came from DSM) modem relay=0, from CLI config=0
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sip_set_modem_caps: Disabling Modem Relay...
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sip_set_modem_caps: Negotiation NOT yet Done. Set the configured values and generate SDP Xcap list
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sip_set_modem_caps: Modem Relay & Passthru both disabled
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sip_set_modem_caps: nse payload = 100, ptru mode = 0, ptru-codec=0, redundancy=0, xid=1, relay=0, sprt-retry=12, latecncy=200, compres-dir=3, dict=1024, strnlen=32
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Media/sipSPISetStreamInfo: 0 Active Streams
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Media/sipSPISetStreamInfo: Number of active streams is zero (0)!
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Media/sipSPISetStreamInfo:
caps.stream_count=0,caps.stream[0].stream_type=0xFFFF, caps.stream_list.xmitFunc=
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Media/sipSPISetStreamInfo: ??unknown??, caps.stream_list.context=
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Media/sipSPISetStreamInfo: 0x0 (gccb)
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/ccsip_caps_ind: Load DSP with codec : g711alaw, Bytes=160, payload = 8
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/ccsip_caps_ind: ccsip_caps_ind: ccb->pld.flags_ipip = 0x400003
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/ccsip_caps_ack: Set forking flag to 0x0
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/copy_channels:
callId 31229 size 108 ptr 0x66CE4EA0)
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 141)
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/ccsip_event_handler:
ccsip_event_handler: peer ID 31229 chans 0x66CE4EA0 event 141 flags 0xC0201C 0x300 0x400003 data 0x66CE4EA0
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_OPEN_CHANNEL_IND: peer ID 31229 chans 0x66CE4EA0 event 141 flags 0xC0201C 0x300 0x400003 data 0x66CE4EA0
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_NEW_MEDIA
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/ccsip_event_handler:
ccsip_event_handler: set event->type = SIPSPI_EV_CC_NEW_MEDIA!: peer ID 31229 chans 0x66CE4EA0 event 141 flags 0xC0201C 0x300 0x400003 data 0x66CE4EA0
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Media/sipSPIUpdCallWithSdpInfo:
Stream type : voice-only
Media line : 1
State : STREAM_ADDING (2)
Callid : -1
Negotiated Codec : g711alaw, bytes :160
Nego. Codec payload : 8 (tx), 8 (rx)
Negotiated DTMF relay : inband-voice
Negotiated NTE payload : 0 (tx), 0 (rx)
Negotiated CN payload : 0
Media Srce Addr/Port : 10.66.7.218:0
Media Dest Addr/Port : 10.200.7.157:37372
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIProcessMediaChanges: sipSPIProcessMediaChanges
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIhandle200OKInvite: ccsip_api_call_connect_media returned: SIP_SUCCESS
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70795398 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD passed.
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIhandle200OKInvite: ccsip_api_call_connected returned: SIP_SUCCESS_DELAYED_MEDIA
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70795398 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (SIP_STATE_RECD_SUCCESS, SUBSTATE_NONE)
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIhandle200OKInvite: starting MediaAck timer(2)
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 26
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sipSPI_ipip_handle_channel_info:
CCSIP:callID 31228 ft: 1, inc 4, 10.200.7.157:37372, codec 6
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
callid 31228, channels 0x66CE4EA0 caps 0x66CE89A8
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp: Peer cap provided: callid = 31228, peer dtmf = 3
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp: callid = 31228, peer not doing RFC2833, peer dtmf = 3, enable NTE_ASSUMED
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
pref dtmf 101
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sipSPIProcessMediaChanges: sipSPIProcessMediaChanges
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/ccsip_bridge: confID = 183, srcCallID = 31228, dstCallID = 31229
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sipSPIUupdateCcCallIds: Old src/dest ccCallids: 31228/31229, new src/dest ccCallids: 31228/31229
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/sipSPIUupdateCcCallIds: Old streamcallid=31228, new streamcallid=31228
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/ccsip_bridge: xcoder_attached = 0, xmitFunc = 1660080152, ccb xmitFunc = 1660080152
*Feb 19 11:10:54.585: //31228/D5950F0DBF34/SIP/Info/ccsip_bridge: ccsip_bridge: Delayed Media case
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/ccsip_bridge: confID = 183, srcCallID = 31229, dstCallID = 31228
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIUupdateCcCallIds: Old src/dest ccCallids: -1/-1, new src/dest ccCallids: 31229/31228
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/sipSPIUupdateCcCallIds: Old streamcallid=-1, new streamcallid=31229
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/ccsip_bridge: xcoder_attached = 0, xmitFunc = 1660080152, ccb xmitFunc = 1660080152
*Feb 19 11:10:54.585: //31229/D5950F0DBF34/SIP/Info/ccsip_bridge: ccsip_bridge: Delayed Media case
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_CONNECT
*Feb 19 11:10:54.585: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_MEDIA_EVENT
*Feb 19 11:10:54.589: //31227/C9CA4240BF33/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 79FB
*Feb 19 11:10:54.589: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[31240] removed.
*Feb 19 11:10:54.589: //31227/C9CA4240BF33/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 19 11:10:54.589: //31227/C9CA4240BF33/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x680BCF18 key=isbchuhdpahep477bpoedh4epk7sb7esasc4@SoftX3000
*Feb 19 11:10:54.589: //31227/C9CA4240BF33/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 19 11:10:54.589: //31227/C9CA4240BF33/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 19 11:10:54.589: //31227/C9CA4240BF33/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 680BCF18
*Feb 19 11:10:54.589: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 6
*Feb 19 11:10:54.589: //31228/D5950F0DBF34/SIP/Event/sipSPICreateRpid: Received Octet3A=0x00 -> Setting ;screen=no ;privacy=off
*Feb 19 11:10:54.589: //31228/D5950F0DBF34/SIP/Info/sipSPISendInviteResponse: Associated container=0x7117BEFC to Invite Response 200
*Feb 19 11:10:54.589: //31228/D5950F0DBF34/SIP/Transport/sipSPISendInviteResponse: Sending 200OK Response to the Transport Layer
*Feb 19 11:10:54.589: //31228/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: msg=0x70BB4CD0, addr=10.104.160.30, port=41357, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=0x615E7A58
*Feb 19 11:10:54.589: //31228/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:10:54.589: //31228/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:10:54.589: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70BB4CD0, addr=10.104.160.30, port=41357, connId=1 for TCP
*Feb 19 11:10:54.589: //31228/D5950F0DBF34/SIP/Info/sentInviteResponse200: Sent 200Ok for Invite in state STATE_SENT_ALERTING
*Feb 19 11:10:54.589: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteResponse200: Transaction active. Facilities will be queued.
*Feb 19 11:10:54.589: //31228/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70782DB8 : State change from (STATE_SENT_ALERTING, SUBSTATE_NONE) to (STATE_SENT_SUCCESS, SUBSTATE_NONE)
*Feb 19 11:10:54.589: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 29
*Feb 19 11:10:54.589: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeaa03332dc41
From: "Aslam Shaik" <2169301>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-477193402169301>
To: <90097339982779>;tag=248B2DBC-3A790097339982779>
Date: Tue, 19 Feb 2013 11:10:42 GMT
Call-ID: 6014400-12315c11-adf1-1ea0680a@10.104.160.30
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: <0097339982779>;party=called;screen=no;privacy=off0097339982779>
Contact: <0097339982779>0097339982779>
Supported: replaces
Session-Expires: 1800;refresher=uac
Require: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 256
v=0
o=CiscoSystemsSIP-GW-UserAgent 9262 4326 IN IP4 10.106.78.7
s=SIP Call
c=IN IP4 10.106.78.7
t=0 0
m=audio 17372 RTP/AVP 8 0 101
c=IN IP4 10.106.78.7
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
*Feb 19 11:10:54.857: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:10:54.857: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 19 11:10:54.857: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x7095EC90, addr=10.104.160.30, port=41357, connid=1, transport=TCP
*Feb 19 11:10:54.857: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:0097339982779@10.106.78.7:5060;transport=tcp SIP/2.0
Date: Tue, 19 Feb 2013 11:03:45 GMT
From: "Aslam Shaik" <2169301>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-477193402169301>
Allow-Events: presence
Content-Length: 212
To: <90097339982779>;tag=248B2DBC-3A790097339982779>
Content-Type: application/sdp
Call-ID: 6014400-12315c11-adf1-1ea0680a@10.104.160.30
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeaa17878c67
CSeq: 101 ACK
Max-Forwards: 70
v=0
o=CiscoSystemsCCM-SIP 2000 1 IN IP4 10.104.160.30
s=SIP Call
c=IN IP4 10.106.71.2
t=0 0
m=audio 31760 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
*Feb 19 11:10:54.857: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x70782DB8
*Feb 19 11:10:54.857: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.104.160.30,Port 41357, Transport 2, SentBy Port 5060
*Feb 19 11:10:54.857: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 19 11:10:54.857: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.104.160.30,Port 41357, Transport 2, SentBy Port 5060
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/act_sentsucc_new_message_request: Transaction Complete. Lock on Facilities released.
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Error/sipSPIProcessCallInfoHeader: Call-Info header with for Unsolicited Notify Absent,Disabling Unsolicited Notifies
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
*Feb 19 11:10:54.857: //-1/xxxxxxxxxxxx/SIP/Info/rtpAvpCodec_to_voipCodec: Dynamic Payload :101 in SDP Body
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/sipSPIDoAudioNegotiation: No matching voice codec found for m-line 1
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(101) could not be reserved.
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/sipSPIDoDTMFRelayNegotiation: RTP-NTE DTMF relay option
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of partial named event(NE) match in fmtp list of events.
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/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
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Error/sipSPIDoAudioNegotiation: Media negotiation failed for m-line 1
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Error/sipSPIDoMediaNegotiation:
no valid fax or audio streams
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Error/sipSPIProcessAckMedia: Disconnecting due to MediaNego failure
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/ccsip_set_cc_cause_for_spi_err: Categorized cause:65, category:278
*Feb 19 11:10:54.857: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[31228], src[6]
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/ccsip_api_request_answer: ACK handling retval: SIP_UNACCEPTABLE_MEDIA_ERR
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/act_sentsucc_new_message_request: ccsip_api_request_answer return val:SIP_UNACCEPTABLE_MEDIA_ERR
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(65) for incoming call
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70782DB8 : State change from (STATE_SENT_SUCCESS, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 19 11:10:54.857: //31228/D5950F0DBF34/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 19 11:10:54.861: //31228/D5950F0DBF34/SIP/Transport/sipSPISendBye: Sending BYE to the transport layer
*Feb 19 11:10:54.861: //31228/D5950F0DBF34/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
*Feb 19 11:10:54.861: //31228/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: msg=0x704D4150, addr=10.104.160.30, port=5060, sentBy_port=0, is_req=1, transport=2, switch=0, callBack=0x615E7CB8
*Feb 19 11:10:54.861: //31228/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:10:54.861: //31228/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:10:54.861: //31228/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x704D4150
*Feb 19 11:10:54.861: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x704D4150, addr=10.104.160.30, port=5060, connId=2 for TCP
*Feb 19 11:10:54.861: //31228/D5950F0DBF34/SIP/Info/sentByeDisconnecting: Sent Bye Request, starting DisconnectTimer
*Feb 19 11:10:54.861: //31228/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70782DB8 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 19 11:10:54.861: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:2169301@10.104.160.30:5060;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 10.106.78.7:5060;branch=z9hG4bK3FA10B7
From: <90097339982779>;tag=248B2DBC-3A790097339982779>
To: "Aslam Shaik" <2169301>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-477193402169301>
Date: Tue, 19 Feb 2013 11:10:54 GMT
Call-ID: 6014400-12315c11-adf1-1ea0680a@10.104.160.30
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 70
Timestamp: 1361272254
CSeq: 101 BYE
Reason: Q.850;cause=86
Content-Length: 0
*Feb 19 11:10:54.861: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 19 11:10:54.861: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 19 11:10:54.861: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
*Feb 19 11:10:54.861: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
*Feb 19 11:10:54.861: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddRouteHeaders: status = TRUE Route: <10.200.7.157:5060>10.200.7.157:5060>
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Info/sipSPISendAck: Associated container=0x7117C244 to Ack
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: msg=0x66CE4F9C, addr=10.200.7.157, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x00000000
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x66CE4F9C
*Feb 19 11:10:54.861: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x66CE4F9C, addr=10.200.7.157, port=5060, connId=1 for UDP
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70795398 : State change from (SIP_STATE_RECD_SUCCESS, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Info/sipSPIPushOrigRequestContainerIntoHolder: Request Container Holder is above threshold...trimming
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 19 11:10:54.861: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddRouteHeaders: status = TRUE Route: <10.200.7.157:5060>10.200.7.157:5060>
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Transport/sipSPISendBye: Sending BYE to the transport layer
*Feb 19 11:10:54.861: //31229/D5950F0DBF34/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Feb 19 11:10:54.861: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:0097339982779@10.200.7.157:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bK3FCBDC
From: "Aslam Shaik" <2169301>;tag=248B1A04-13842169301>
To: <0097339982779>;tag=sbc0804h4bsb2sa-CC-300097339982779>
Date: Tue, 19 Feb 2013 11:10:42 GMT
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 70
Route: <10.200.7.157:5060>10.200.7.157:5060>
Timestamp: 1361272254
CSeq: 103 BYE
Reason: Q.850;cause=65
Content-Length: 0
*Feb 19 11:10:55.525: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 19 11:10:55.525: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:10:55.525: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 19 11:10:55.525: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:2169301@10.66.7.218:5060 SIP/2.0
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKfhfcsk7e7upe2pakpfphfddaoT08070
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
From: <0097339982779>;tag=sbc0804h4bsb2sa-CC-300097339982779>
To: "Aslam Shaik"<2169301>;tag=248B1A04-13842169301>
CSeq: 1 BYE
Max-Forwards: 70
Content-Length: 0
*Feb 19 11:10:55.525: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:10:55.525: //31229/D5950F0DBF34/SIP/Info/sipSPILocateInviteDialogCCB: ****Found CCB in UAC table
*Feb 19 11:10:55.525: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 19 11:10:55.525: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 19 11:10:55.525: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 19 11:10:55.525: //31229/D5950F0DBF34/SIP/Transport/sipSPISendByeResponse: Sending BYE Response to the transport layer
*Feb 19 11:10:55.525: //31229/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: msg=0x66CE4F9C, addr=10.200.7.157, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x00000000
*Feb 19 11:10:55.525: //31229/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:10:55.525: //31229/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:10:55.525: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x66CE4F9C, addr=10.200.7.157, port=5060, connId=0 for UDP
*Feb 19 11:10:55.549: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKfhfcsk7e7upe2pakpfphfddaoT08070
From: <0097339982779>;tag=sbc0804h4bsb2sa-CC-300097339982779>
To: "Aslam Shaik" <2169301>;tag=248B1A04-13842169301>
Date: Tue, 19 Feb 2013 11:10:55 GMT
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 BYE
Content-Length: 0
*Feb 19 11:10:55.549: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 19 11:10:55.549: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 19 11:10:55.549: //31229/D5950F0DBF34/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 19 11:10:55.549: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddRouteHeaders: status = TRUE Route: <10.200.7.157:5060>10.200.7.157:5060>
*Feb 19 11:10:55.549: //31229/D5950F0DBF34/SIP/Transport/sipSPISendBye: Sending BYE to the transport layer
*Feb 19 11:10:55.549: //31229/D5950F0DBF34/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Feb 19 11:10:55.549: //31229/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: msg=0x66CE4F9C, addr=10.200.7.157, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x00000000
*Feb 19 11:10:55.549: //31229/D5950F0DBF34/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:10:55.549: //31229/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:10:55.549: //31229/D5950F0DBF34/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x66CE4F9C
*Feb 19 11:10:55.549: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x66CE4F9C, addr=10.200.7.157, port=5060, connId=1 for UDP
*Feb 19 11:10:55.549: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:10:55.549: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x7095EC90
*Feb 19 11:10:55.549: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x7095EC90, addr=10.104.160.30, port=5060, connid=2, transport=TCP
*Feb 19 11:10:55.549: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:10:55.549: //31228/D5950F0DBF34/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x70782DB8
*Feb 19 11:10:55.549: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Date: Tue, 19 Feb 2013 11:03:57 GMT
From: <90097339982779>;tag=248B2DBC-3A790097339982779>
Content-Length: 0
To: "Aslam Shaik" <2169301>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-477193402169301>
Call-ID: 6014400-12315c11-adf1-1ea0680a@10.104.160.30
Via: SIP/2.0/TCP 10.106.78.7:5060;branch=z9hG4bK3FA10B7
CSeq: 101 BYE
*Feb 19 11:10:55.549: //31228/D5950F0DBF34/SIP/Info/sipSPIIcpifUpdate: CallState: 4 Playout: 0 DiscTime:61310911 ConnTime 61310815
*Feb 19 11:10:55.549: //31228/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70782DB8 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*Feb 19 11:10:55.553: //31228/D5950F0DBF34/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x70782DB8
State of The Call : STATE_DEAD
TCP Sockets Used : YES
Calling Number : 2169301
Called Number : 90097339982779
Source IP Address (Sig ): 10.106.78.7
Destn SIP Req Addr:Port : 10.104.160.30:5060
Destn SIP Resp Addr:Port : 10.104.160.30:41357
Destination Name : 10.104.160.30
*Feb 19 11:10:55.553: //31228/D5950F0DBF34/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : No Codec
Negotiated Codec Bytes : 0
Nego. Codec payload : 255 (tx), 255 (rx)
Negotiated Dtmf-relay : 0
Dtmf-relay Payload : 0 (tx), 0 (rx)
Source IP Address (Media): 10.106.78.7
Source IP Port (Media): 17372
Destn IP Address (Media): 0.0.0.0
Destn IP Port (Media): 0
Orig Destn IP Address:Port (Media): 0.0.0.0:0
*Feb 19 11:10:55.553: //31228/D5950F0DBF34/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 86
Disconnect Cause (SIP) : 200
*Feb 19 11:10:55.553: //31228/D5950F0DBF34/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 79FC
*Feb 19 11:10:55.553: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[31241] removed.
*Feb 19 11:10:55.553: //31228/D5950F0DBF34/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 19 11:10:55.553: //31228/D5950F0DBF34/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x70782DB8 key=6014400-12315c11-adf1-1ea0680a@10.104.160.3090097339982779
*Feb 19 11:10:55.553: //31228/D5950F0DBF34/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 19 11:10:55.553: //31228/D5950F0DBF34/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x70782DB8 key=6014400-12315c11-adf1-1ea0680a@10.104.160.30248B2DBC-3A7
*Feb 19 11:10:55.553: //31228/D5950F0DBF34/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 19 11:10:55.553: //31228/D5950F0DBF34/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 70782DB8
*Feb 19 11:10:55.553: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[31241]
*Feb 19 11:10:55.553: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:10:55.553: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 19 11:10:55.553: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:10:55.553: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bK3FCBDC
Record-Route: <10.200.7.157:5060>10.200.7.157:5060>
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
From: "Aslam Shaik"<2169301>;tag=248B1A04-13842169301>
To: <0097339982779>;tag=sbc0804h4bsb2sa-CC-300097339982779>
CSeq: 103 BYE
Warning: 399 SoftX3000 "SS030001F00154L00763[00000] Glare Bye condition encountered"
Content-Length: 0
*Feb 19 11:10:55.553: //31229/D5950F0DBF34/SIP/Info/sipSPIIcpifUpdate: CallState: 4 Playout: 0 DiscTime:61310911 ConnTime 61310814
*Feb 19 11:10:55.553: //31229/D5950F0DBF34/SIP/State/sipSPIChangeState: 0x70795398 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*Feb 19 11:10:55.553: //31229/D5950F0DBF34/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x70795398
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 2169301
Called Number : 0097339982779
Source IP Address (Sig ): 10.66.7.218
Destn SIP Req Addr:Port : 10.200.7.157:5060
Destn SIP Resp Addr:Port : 10.200.7.157:5060
Destination Name : 10.200.7.157
*Feb 19 11:10:55.713: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bK3FCBDC
Record-Route: <10.200.7.157:5060>10.200.7.157:5060>
Call-ID: D59647F6-79BB11E2-BF39F6CE-CD79320E@10.66.7.218
From: "Aslam Shaik"<2169301>;tag=248B1A04-13842169301>
To: <0097339982779>;tag=sbc0804h4bsb2sa-CC-300097339982779>
CSeq: 103 BYE
Warning: 399 SoftX3000 "SS030001F00154L00763[00000] Glare Bye condition encountered"
Content-Length: 0
*Feb 19 11:10:55.713: //-1/xxxxxxxxxxxx/SIP/Error/sipSPILocateInviteDialogCCB: Could not find ccb for response
*Feb 19 11:11:02.713: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 19 11:11:02.713: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 19 11:11:02.713: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 19 11:11:02.713: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:10.66.7.218:5060 SIP/2.0
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKkdpfo7s4tuaphto7teau4fckuT22652
Call-ID: isbc4bsb4kbb2c7u2dee2bkc4sbauscted4h@SoftX3000
From: <10.66.7.218:5060>;tag=sbc0802hdbpeop710.66.7.218:5060>
To: <10.66.7.218>10.66.7.218>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0
*Feb 19 11:11:02.713: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 19 11:11:02.713: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x70BA16B4) with key=[31244] to table
*Feb 19 11:11:02.713: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 19 11:11:02.713: //-1/E1B54E3ABF3B/SIP/State/sipSPIChangeState: 0x70BA16B4 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 19 11:11:02.713: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 19 11:11:02.713: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 19 11:11:02.713: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 19 11:11:02.713: //31231/E1B54E3ABF3B/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 19 11:11:02.713: //31231/E1B54E3ABF3B/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
*Feb 19 11:11:02.713: //31231/E1B54E3ABF3B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
*Feb 19 11:11:02.713: //31231/E1B54E3ABF3B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
*Feb 19 11:11:02.713: //31231/E1B54E3ABF3B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
*Feb 19 11:11:02.713: //31231/E1B54E3ABF3B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
*Feb 19 11:11:02.713: //31231/E1B54E3ABF3B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
*Feb 19 11:11:02.713: //31231/E1B54E3ABF3B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
*Feb 19 11:11:02.713: //31231/E1B54E3ABF3B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
*Feb 19 11:11:02.717: //31231/E1B54E3ABF3B/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.66.7.218
*Feb 19 11:11:02.717: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
*Feb 19 11:11:02.717: //31231/E1B54E3ABF3B/SIP/Info/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS
*Feb 19 11:11:02.717: //31231/E1B54E3ABF3B/SIP/State/sipSPIChangeState: 0x70BA16B4 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
*Feb 19 11:11:02.717: //31231/E1B54E3ABF3B/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 19 11:11:02.717: //31231/E1B54E3ABF3B/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x70BA16B4 key=isbc4bsb4kbb2c7u2dee2bkc4sbauscted4h@SoftX3000
*Feb 19 11:11:02.717: //31231/E1B54E3ABF3B/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 79FF to table
*Feb 19 11:11:02.717: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38
*Feb 19 11:11:02.717: //31231/E1B54E3ABF3B/SIP/Info/sipSPISendOptionsResponse: Associated container=0x7117C8D4 to Options Response
*Feb 19 11:11:02.717: //31231/E1B54E3ABF3B/SIP/Transport/sipSPITransportSendMessage: msg=0x70861A28, addr=10.200.7.157, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x615E9E98
*Feb 19 11:11:02.717: //31231/E1B54E3ABF3B/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 19 11:11:02.717: //31231/E1B54E3ABF3B/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 19 11:11:02.717: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70861A28, addr=10.200.7.157, port=5060, connId=0 for UDP
*Feb 19 11:11:02.717: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKkdpfo7s4tuaphto7teau4fckuT22652
From: <10.66.7.218:5060>;tag=sbc0802hdbpeop710.66.7.218:5060>
To: <10.66.7.218>;tag=248B6974-8DD10.66.7.218>
Date: Tue, 19 Feb 2013 11:11:02 GMT
Call-ID: isbc4bsb4kbb2c7u2dee2bkc4sbauscted4h@SoftX3000
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 OPTIONS
Supported: 100rel,resource-priority,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Content-Type: application/sdp
Content-Length: 164
v=0
o=CiscoSystemsSIP-GW-UserAgent 8661 7456 IN IP4 10.106.78.7
s=SIP Call
c=IN IP4 10.66.7.218
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 10.66.7.218
02-19-2013 05:05 AM
Ashley,
You are using sip to sip so dont bother about the other debugs..Please add this to your config
voice service voip
sip
early-offer forced
Do a test again and send only "debug ccsip messsages"
Please rate all useful posts
"opportunity is a haughty goddess who waste no time with those who are unprepared"
02-19-2013 05:18 AM
Hi..thank you for taking time to assist. Before I proceed with the change that you have proposed, I wanted to mention that I removed codec preference 2 g711ulaw from my config (as below) and it is now working fine. Ony codec preference 1 g711alaw is now in the config which is what the provider is expecting.
However I would like to reinstate codec preference 2 g711ulaw in my config but see that there is no attribute field in the below. Am i missing something?
v=0
o=CiscoSystemsSIP-GW-UserAgent 8661 7456 IN IP4 10.106.78.7
s=SIP Call
c=IN IP4 10.66.7.218
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 10.66.7.218
02-19-2013 05:37 AM
G711ulaw is certainly in the attributes..Looking at the M-line
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
CUBE is offering
18=G729
0=PCMU---------G711u
8=PCMA-----G711a
And your provider certainly supports G711ulaw and alaw
This is the SDP in the response received from your ITSP
m=audio 37372 RTP/AVP 8 0 18 4 2 98 99 97
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:4 G723/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:98 G726-40/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
a=fmtp:18 annexb=yes
Please rate all useful posts
"opportunity is a haughty goddess who waste no time with those who are unprepared"
02-23-2013 09:52 PM
Hi aokanlawon
Thank you for the insights you have provided which has helped in resolving the issue and furthered my understanding.
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide