SIP/2.0 503 service unavailable

aysar3000 · ‎05-31-2014

Dears

when i make a phone call to a mobile number sometimes it work just fine and sometimes it drop and give a busy tone and i had to try 3 to 10 times before it connect spechially if the number is not in use before

this is the syslog from debug

May 30 20:19:34.284: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:90555769123@192.168.200.86:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.200.53:5060;branch=z9hG4bK3bd451bf17e0

From: "Aysar Mohamed" <sip:2217156@192.168.200.53>;tag=37693~244641b0-36ac-434c-91c1-823f25a68b28-18299026

To: <sip:90555769123@192.168.200.86>

Date: Fri, 30 May 2014 20:19:34 GMT

Call-ID: b5a49480-3881e7d6-13a9-35c8a8c0@192.168.200.53

Supported: timer,resource-priority,replaces

Min-SE: 1800

User-Agent: Cisco-CUCM8.6

Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY

CSeq: 101 INVITE

Expires: 180

Allow-Events: presence, kpml

Supported: X-cisco-srtp-fallback

Supported: Geolocation

Call-Info: <sip:192.168.200.53:5060>;method="NOTIFY;Event=telephone-event;Duration=500"

Cisco-Guid: 3047462016-0000065536-0000004549-0902342848

Session-Expires: 1800

P-Asserted-Identity: "Aysar Mohamed" <sip:2217156@192.168.200.53>

Remote-Party-ID: "Aysar Mohamed" <sip:2217156@192.168.200.53>;party=calling;screen=yes;privacy=off

Contact: <sip:2217156@192.168.200.53:5060>

Max-Forwards: 70

Content-Length: 0

May 30 20:19:34.284: //-1/B5A494800000/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=2217156
   ----- ccCallInfo IE subfields -----
   cisco-ani=2217156
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=1
   dest=90555769123
   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

May 30 20:19:34.288: //-1/B5A494800000/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x30CF41D4, Call Info(
   Calling Number=2217156,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=90555769123(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=465
May 30 20:19:34.288: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 30 20:19:34.288: :cc_get_feature_vsa malloc success
May 30 20:19:34.288: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 30 20:19:34.288: cc_get_feature_vsa count is 1
May 30 20:19:34.288: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 30 20:19:34.288: :FEATURE_VSA attributes are: feature_name:0,feature_time:832953048,feature_id:85
May 30 20:19:34.288: //465/B5A494800000/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=2217156(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=90555769123(TON=Unknown, NPI=Unknown))
May 30 20:19:34.288: //465/B5A494800000/CCAPI/cc_process_call_setup_ind:
   Event=0x2B82D890
May 30 20:19:34.288: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 90555769123
May 30 20:19:34.288: //465/B5A494800000/CCAPI/ccCallSetContext:
   Context=0x2ABC2E44
May 30 20:19:34.288: //465/B5A494800000/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 465 with tag 0 to app "_ManagedAppProcess_Default"
May 30 20:19:34.288: //465/B5A494800000/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
May 30 20:19:34.288: //465/B5A494800000/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=TRUE, Mode=0,
   Outgoing Dial-peer=802, Params=0x2ABC19D4, Progress Indication=NULL(0)
May 30 20:19:34.288: //465/B5A494800000/CCAPI/ccCheckClipClir:
   In: Calling Number=2217156(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
May 30 20:19:34.288: //465/B5A494800000/CCAPI/ccCheckClipClir:
   Out: Calling Number=2217156(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
May 30 20:19:34.288: //465/B5A494800000/CCAPI/ccCallSetupRequest:
   Destination Pattern=9T, Called Number=0555769123, Digit Strip=FALSE
May 30 20:19:34.288: //465/B5A494800000/CCAPI/ccCallSetupRequest:
   Calling Number=2217156(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=0555769123(TON=Unknown, NPI=Unknown),
   Redirect Number=, Display Info=Aysar Mohamed
   Account Number=2217156, Final Destination Flag=TRUE,
   Guid=B5A49480-0001-0000-0000-11C535C8A8C0, Outgoing Dial-peer=802
May 30 20:19:34.288: //465/B5A494800000/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=2217156
   ----- ccCallInfo IE subfields -----
   cisco-ani=2217156
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=1
   dest=0555769123
   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

May 30 20:19:34.288: //465/B5A494800000/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x30CF41D4, Interface Type=3, Destination=, Mode=0x0,
   Call Params(Calling Number=2217156,(Calling Name=Aysar Mohamed)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=0555769123(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=802, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
May 30 20:19:34.288: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 30 20:19:34.288: :cc_get_feature_vsa malloc success
May 30 20:19:34.288: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 30 20:19:34.288: cc_get_feature_vsa count is 2
May 30 20:19:34.288: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 30 20:19:34.288: :FEATURE_VSA attributes are: feature_name:0,feature_time:832952824,feature_id:86
May 30 20:19:34.292: //466/B5A494800000/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
May 30 20:19:34.292: //466/B5A494800000/CCAPI/ccCallSetContext:
   Context=0x2ABC1984
May 30 20:19:34.292: //465/B5A494800000/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=802
May 30 20:19:34.292: //466/B5A494800000/CCAPI/cc_api_call_proceeding:
   Interface=0x30CF41D4, Progress Indication=NULL(0)
May 30 20:19:34.292: //465/B5A494800000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.200.53:5060;branch=z9hG4bK3bd451bf17e0

From: "Aysar Mohamed" <sip:2217156@192.168.200.53>;tag=37693~244641b0-36ac-434c-91c1-823f25a68b28-18299026

To: <sip:90555769123@192.168.200.86>

Date: Fri, 30 May 2014 20:19:34 GMT

Call-ID: b5a49480-3881e7d6-13a9-35c8a8c0@192.168.200.53

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0

May 30 20:19:34.292: //466/B5A494800000/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:0555769123@10.208.9.69:5060 SIP/2.0

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK461C

Remote-Party-ID: "Aysar Mohamed" <sip:2217156@172.29.7.94>;party=calling;screen=yes;privacy=off

From: "Aysar Mohamed" <sip:2217156@172.29.7.94>;tag=7394E4-1898

To: <sip:0555769123@10.208.9.69>

Date: Fri, 30 May 2014 20:19:34 GMT

Call-ID: 8C9F6977-E76E11E3-8277BE8C-EC3B12A0@172.29.7.94

Supported: timer,resource-priority,replaces,sdp-anat

Min-SE: 1800

Cisco-Guid: 3047462016-0000065536-0000004549-0902342848

User-Agent: Cisco-SIPGateway/IOS-12.x

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Timestamp: 1401481174

Contact: <sip:2217156@172.29.7.94:5060>

Call-Info: <sip:172.29.7.94:5060>;method="NOTIFY;Event=telephone-event;Duration=2000"

Expires: 180

Allow-Events: kpml, telephone-event

Max-Forwards: 69

Session-Expires: 1800

Content-Length: 0

May 30 20:19:34.300: //466/B5A494800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK461C

Call-ID: 8C9F6977-E76E11E3-8277BE8C-EC3B12A0@172.29.7.94

From: "Aysar Mohamed"<sip:2217156@172.29.7.94>;tag=7394E4-1898

To: <sip:0555769123@10.208.9.69>

CSeq: 101 INVITE

Content-Length: 0

May 30 20:19:34.612: //466/B5A494800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK461C

Record-Route: <sip:10.208.9.69:5060;transport=udp;lr>

Call-ID: 8C9F6977-E76E11E3-8277BE8C-EC3B12A0@172.29.7.94

From: "Aysar Mohamed"<sip:2217156@172.29.7.94>;tag=7394E4-1898

To: <sip:0555769123@10.208.9.69>;tag=sbc0806eppk5yip-CC-57

CSeq: 101 INVITE

Contact: <sip:0555769123@10.208.9.69:5060;user=phone>

Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER

Content-Length: 328

Content-Type: application/sdp

v=0

o=- 17192647 17192647 IN IP4 10.208.9.69

s=SBC call

c=IN IP4 10.208.9.69

t=0 0

m=audio 39910 RTP/AVP 8 0 102 102 18 116

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:102 AMR/8000

a=rtpmap:18 G729/8000

a=rtpmap:116 telephone-event/8000

a=ptime:5

a=fmtp:116 0-15

a=fmtp:18 annexb=yes

May 30 20:19:34.612: %SIP-3-UNSUPPORTED: Unsupported ptime value
May 30 20:19:34.612: //466/B5A494800000/CCAPI/cc_api_caps_ind:
   Destination Interface=0x0, Destination Call Id=-1, Source Call Id=466,
   Caps(Codec=0x2, Fax Rate=0x2, Vad=0x1,
   Modem=0x0, Codec Bytes=160, Signal Type=2)
May 30 20:19:34.612: //466/B5A494800000/CCAPI/cc_api_caps_ind:
   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
   Playout Max=1000(ms), Fax Nom=300(ms))
May 30 20:19:34.612: //465/B5A494800000/CCAPI/cc_api_caps_ack:
   Destination Interface=0x0, Destination Call Id=-1, Source Call Id=465,
   Caps(Codec=g729r8(0x4), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),
   Modem=OFF(0x0), Codec Bytes=20, Signal Type=2, Seq Num Start=3882)
May 30 20:19:34.612: //465/B5A494800000/CCAPI/cc_api_caps_ack:
   Destination Interface=0x0, Destination Call Id=-1, Source Call Id=465,
   Caps(Codec=g729r8(0x4), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),
   Modem=OFF(0x0), Codec Bytes=20, Signal Type=2, Seq Num Start=3882)
May 30 20:19:34.612: //466/B5A494800000/CCAPI/cc_api_event_indication:
   Event=170, Call Id=466
May 30 20:19:34.612: //466/B5A494800000/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
May 30 20:19:34.612: //466/B5A494800000/CCAPI/cc_api_event_indication:
   Event=98, Call Id=466
May 30 20:19:34.612: //466/B5A494800000/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
May 30 20:19:34.612: //466/B5A494800000/CCAPI/cc_api_call_cut_progress:
   Interface=0x30CF41D4, Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1),
   Cause Value=0
May 30 20:19:34.612: //466/B5A494800000/CCAPI/cc_api_call_cut_progress:
   Call Entry(Responsed=TRUE)
May 30 20:19:34.612: //465/B5A494800000/CCAPI/ccCallCutProgress:
   Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1), Cause Value=0
   Voice Call Send Alert=FALSE, Call Entry(Alert Sent=FALSE)
May 30 20:19:34.612: //465/B5A494800000/CCAPI/ccCallCutProgress:
   Call Entry(Responsed=TRUE)
May 30 20:19:34.612: //465/B5A494800000/CCAPI/ccConferenceCreate:
   (confID=0x30C11410, callID1=0x1D1, gcid=8C9E3127-E76E11E3-8274BE8C-EC3B12A0, tag=0x0)
May 30 20:19:34.616: //466/B5A494800000/CCAPI/ccConferenceCreate:
   (confID=0x30C11410, callID2=0x1D2, gcid=8C9E3127-E76E11E3-8274BE8C-EC3B12A0, tag=0x0)
May 30 20:19:34.616: //465/B5A494800000/CCAPI/ccConferenceCreate:
   Conference Id=0x30C11410, Call Id1=465, Call Id2=466, Tag=0x0
May 30 20:19:34.616: //465/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

May 30 20:19:34.616: cc_api_get_xcode_stream : 4702
May 30 20:19:34.616: //466/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

May 30 20:19:34.616: cc_api_get_xcode_stream : 4702
May 30 20:19:34.616: //465/B5A494800000/CCAPI/ccConferenceCreate:

May 30 20:19:34.616: ccConferenceCreate: ret1=0, codecMask1=2, bytes1=160, negot1=0, dtmf1=0
                    ret2=0, codecMask2=2, bytes2=160, negot2=1, dtmf2=6,
                    tx_dynamic_pt1=0, rx_dynamic_pt1=0, codec_mode1=0, params_bitmap1 =0
                    tx_dynamic_pt2=8, rx_dynamic_pt2=8, codec_mode2=0, params_bitmap2 =0
May 30 20:19:34.616: //465/B5A494800000/CCAPI/ccConferenceCreate:
   delay media to slow start case, codec negotation is not done
May 30 20:19:34.616: //465/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

May 30 20:19:34.616: cc_api_get_xcode_stream : 4702
May 30 20:19:34.616: //465/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

May 30 20:19:34.616: cc_api_get_xcode_stream : 4702
May 30 20:19:34.616: //465/B5A494800000/CCAPI/cc_api_bridge_done:
   Conference Id=0x16, Source Interface=0x30CF41D4, Source Call Id=465,
   Destination Call Id=466, Disposition=0x0, Tag=0x0
May 30 20:19:34.616: //466/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

May 30 20:19:34.616: cc_api_get_xcode_stream : 4702
May 30 20:19:34.616: //466/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

May 30 20:19:34.616: cc_api_get_xcode_stream : 4702
May 30 20:19:34.616: //466/B5A494800000/CCAPI/cc_api_bridge_done:
   Conference Id=0x16, Source Interface=0x30CF41D4, Source Call Id=466,
   Destination Call Id=465, Disposition=0x0, Tag=0x0
May 30 20:19:34.616: //465/B5A494800000/CCAPI/cc_generic_bridge_done:
   Conference Id=0x16, Source Interface=0x30CF41D4, Source Call Id=466,
   Destination Call Id=465, Disposition=0x0, Tag=0x0
May 30 20:19:34.616: //465/B5A494800000/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x16, Destination Call Id=466)
May 30 20:19:34.616: //466/B5A494800000/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x16, Destination Call Id=465)
May 30 20:19:34.616: //465/B5A494800000/CCAPI/cc_process_notify_bridge_done:
   Conference Id=0x16, Call Id1=465, Call Id2=466
May 30 20:19:34.616: //465/B5A494800000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 183 Session Progress

Via: SIP/2.0/UDP 192.168.200.53:5060;branch=z9hG4bK3bd451bf17e0

From: "Aysar Mohamed" <sip:2217156@192.168.200.53>;tag=37693~244641b0-36ac-434c-91c1-823f25a68b28-18299026

To: <sip:90555769123@192.168.200.86>;tag=739628-1BDB

Date: Fri, 30 May 2014 20:19:34 GMT

Call-ID: b5a49480-3881e7d6-13a9-35c8a8c0@192.168.200.53

CSeq: 101 INVITE

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Remote-Party-ID: <sip:0555769123@192.168.200.86>;party=called;screen=yes;privacy=off

Contact: <sip:90555769123@192.168.200.86:5060>

Supported: sdp-anat

Server: Cisco-SIPGateway/IOS-12.x

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 233

v=0

o=CiscoSystemsSIP-GW-UserAgent 2639 5276 IN IP4 192.168.200.86

s=SIP Call

c=IN IP4 192.168.200.86

t=0 0

m=audio 18288 RTP/AVP 8 0 19

c=IN IP4 192.168.200.86

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:19 CN/8000

May 30 20:19:34.680: //466/B5A494800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 500 Server Internal Error

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK461C

Record-Route: <sip:10.208.9.69:5060;transport=udp;lr>

Call-ID: 8C9F6977-E76E11E3-8277BE8C-EC3B12A0@172.29.7.94

From: "Aysar Mohamed"<sip:2217156@172.29.7.94>;tag=7394E4-1898

To: <sip:0555769123@10.208.9.69>;tag=sbc0806eppk5yip-CC-57

CSeq: 101 INVITE

Reason: Q.850;cause=127;text="interworking unspecified"

Warning: 399 - "SoftX3000 R601-CCU Rel POS:[3103] Release from CR"

Content-Length: 0

May 30 20:19:34.680: //466/B5A494800000/CCAPI/cc_api_call_disconnected:
   Cause Value=41, Interface=0x30CF41D4, Call Id=466
May 30 20:19:34.680: //466/B5A494800000/CCAPI/cc_api_call_disconnected:
   Call Entry(Responsed=TRUE, Cause Value=41, Retry Count=0)
May 30 20:19:34.680: //465/B5A494800000/CCAPI/ccCallReleaseResources:
   release reserved xcoding resource.
May 30 20:19:34.680: //466/B5A494800000/CCAPI/ccCallSetAAA_Accounting:
   Accounting=0, Call Id=466
May 30 20:19:34.680: //465/B5A494800000/CCAPI/ccConferenceDestroy:
   Conference Id=0x16, Tag=0x0
May 30 20:19:34.680: //465/B5A494800000/CCAPI/cc_api_bridge_drop_done:
   Conference Id=0x16, Source Interface=0x30CF41D4, Source Call Id=465,
   Destination Call Id=466, Disposition=0x0, Tag=0x0
May 30 20:19:34.680: //466/B5A494800000/CCAPI/cc_api_bridge_drop_done:
   Conference Id=0x16, Source Interface=0x30CF41D4, Source Call Id=466,
   Destination Call Id=465, Disposition=0x0, Tag=0x0
May 30 20:19:34.680: //465/B5A494800000/CCAPI/cc_generic_bridge_done:
   Conference Id=0x16, Source Interface=0x30CF41D4, Source Call Id=466,
   Destination Call Id=465, Disposition=0x0, Tag=0x0
May 30 20:19:34.680: //466/B5A494800000/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:0555769123@10.208.9.69:5060 SIP/2.0

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK461C

From: "Aysar Mohamed" <sip:2217156@172.29.7.94>;tag=7394E4-1898

To: <sip:0555769123@10.208.9.69>;tag=sbc0806eppk5yip-CC-57

Date: Fri, 30 May 2014 20:19:34 GMT

Call-ID: 8C9F6977-E76E11E3-8277BE8C-EC3B12A0@172.29.7.94

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: kpml, telephone-event

Content-Length: 0

May 30 20:19:34.684: //466/B5A494800000/CCAPI/ccCallDisconnect:
   Cause Value=41, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=41)
May 30 20:19:34.684: //466/B5A494800000/CCAPI/ccCallDisconnect:
   Cause Value=41, Call Entry(Responsed=TRUE, Cause Value=41)
May 30 20:19:34.684: //466/B5A494800000/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x30CF41D4, Tag=0x0, Call Id=466,
   Call Entry(Disconnect Cause=41, Voice Class Cause Code=0, Retry Count=0)
May 30 20:19:34.684: //466/B5A494800000/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
May 30 20:19:34.684: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

May 30 20:19:34.684: :cc_free_feature_vsa freeing 31A5D9F0
May 30 20:19:34.684: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

May 30 20:19:34.684: vsacount in free is 1
May 30 20:19:34.684: //465/B5A494800000/CCAPI/ccCallDisconnect:
   Cause Value=41, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
May 30 20:19:34.684: //465/B5A494800000/CCAPI/ccCallDisconnect:
   Cause Value=41, Call Entry(Responsed=TRUE, Cause Value=41)
May 30 20:19:34.684: //465/B5A494800000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 503 Service Unavailable

Via: SIP/2.0/UDP 192.168.200.53:5060;branch=z9hG4bK3bd451bf17e0

From: "Aysar Mohamed" <sip:2217156@192.168.200.53>;tag=37693~244641b0-36ac-434c-91c1-823f25a68b28-18299026

To: <sip:90555769123@192.168.200.86>;tag=739628-1BDB

Date: Fri, 30 May 2014 20:19:34 GMT

Call-ID: b5a49480-3881e7d6-13a9-35c8a8c0@192.168.200.53

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Reason: Q.850;cause=41

Content-Length: 0

May 30 20:19:34.684: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:90555769123@192.168.200.86:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.200.53:5060;branch=z9hG4bK3bd451bf17e0

From: "Aysar Mohamed" <sip:2217156@192.168.200.53>;tag=37693~244641b0-36ac-434c-91c1-823f25a68b28-18299026

To: <sip:90555769123@192.168.200.86>;tag=739628-1BDB

Date: Fri, 30 May 2014 20:19:34 GMT

Call-ID: b5a49480-3881e7d6-13a9-35c8a8c0@192.168.200.53

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: presence, kpml

Content-Length: 0

May 30 20:19:34.688: //465/B5A494800000/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x30CF41D4, Tag=0x0, Call Id=465,
   Call Entry(Disconnect Cause=41, Voice Class Cause Code=0, Retry Count=0)
May 30 20:19:34.688: //465/B5A494800000/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
May 30 20:19:34.688: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

May 30 20:19:34.688: :cc_free_feature_vsa freeing 31A5DAD0
May 30 20:19:34.688: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

May 30 20:19:34.688: vsacount in free is 0
May 30 20:19:36.044: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:172.29.7.94:5060 SIP/2.0

Via: SIP/2.0/UDP 10.208.9.69:5060;branch=z9hG4bKmisco3ykfiooegpygsphkocp1T20326

Call-ID: isbcfemyk1p1mkteets1tcmi53eeehfhikcp@SoftX3000

From: <sip:172.29.7.94:5060>;tag=sbc0803k1pyk51o

To: <sip:172.29.7.94>

CSeq: 1 OPTIONS

Max-Forwards: 70

Content-Length: 0

May 30 20:19:36.048: //467/8DAABF6C8278/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.208.9.69:5060;branch=z9hG4bKmisco3ykfiooegpygsphkocp1T20326

From: <sip:172.29.7.94:5060>;tag=sbc0803k1pyk51o

To: <sip:172.29.7.94>;tag=739BBC-1CE2

Date: Fri, 30 May 2014 20:19:36 GMT

Call-ID: isbcfemyk1p1mkteets1tcmi53eeehfhikcp@SoftX3000

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 1 OPTIONS

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Accept: application/sdp

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Content-Type: application/sdp

Content-Length: 446

v=0

o=CiscoSystemsSIP-GW-UserAgent 3496 1601 IN IP4 172.29.7.94

s=SIP Call

c=IN IP4 172.29.7.94

t=0 0

m=audio 0 RTP/AVP 18 0 8 9 4 2 15

c=IN IP4 172.29.7.94

m=image 0 udptl t38

c=IN IP4 172.29.7.94

a=T38FaxVersion:0

a=T38MaxBitRate:9600

a=T38FaxFillBitRemoval:0

a=T38FaxTranscodingMMR:0

a=T38FaxTranscodingJBIG:0

a=T38FaxRateManagement:transferredTCF

a=T38FaxMaxBuffer:200

a=T38FaxMaxDatagram:320

a=T38FaxUdpEC:t38UDPRedundancy

My SIP GW internal ip address is 192.168.200.86

and the Public IP is : 172.29.7.94

My CUCM is 192.168.200.53

my GW Config is :

voice service voip
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none
sip
registrar server
!
voice class codec 1
codec preference 1 g711alaw
codec preference 2 g711ulaw
codec preference 3 g729r8
codec preference 4 g729br8
!

voice translation-rule 3
rule 1 /^9/ /\1/
!
voice translation-rule 4
rule 4 /^22217/ /7/
rule 5 /^2217/ /7/
rule 6 /^022217/ /7/
rule 7 /^0122217/ /7/
!
voice translation-rule 5
rule 1 /^5/ /905/
rule 2 /^1/ /901/
rule 3 /^2/ /902/
rule 4 /^3/ /903/
rule 5 /^4/ /904/
rule 6 /^6/ /906/
rule 7 /^7/ /907/
rule 8 /^8/ /908/
rule 10 /^00/ /900/
rule 11 /'+'/ /900/
!
!
voice translation-profile OUT
translate called 3
!
voice translation-profile REDIAL
translate calling 5
!
voice translation-profile SIP-NEW
translate called 4
!
!
!
application
service mva http://192.168.200.53:8080/ccmivr/pages/IVRMainpage.vxml
!
service ccm http://192.168.200.53:8080/ccmivr/pages/IVRMainpage.vxml
!
!
license udi pid CISCO2921/K9 sn FCZ164960G0
hw-module pvdm 0/0
!
hw-module pvdm 0/1
!

interface Embedded-Service-Engine0/0
no ip address
shutdown
!
interface GigabitEthernet0/0
description $ETH-LAN$$ETH-SW-LAUNCH$$INTF-INFO-GE 0/0$
ip address 192.168.200.86 255.255.255.0
duplex auto
speed auto
!
interface GigabitEthernet0/1
ip address 172.29.7.94 255.255.255.252
duplex auto
speed auto

ip http server
ip http access-class 23
ip http authentication local
no ip http secure-server
ip http timeout-policy idle 60 life 86400 requests 10000
!
ip route 0.0.0.0 0.0.0.0 192.168.200.1
ip route 10.208.9.0 255.255.255.0 172.29.7.93
!
access-list 23 permit 10.10.10.0 0.0.0.7
!
!
!
control-plane
!
!
!
!
mgcp profile default
!
sccp local GigabitEthernet0/0
sccp ccm 192.168.200.53 identifier 1 priority 1 version 7.0
sccp
!
sccp ccm group 1
associate ccm 1 priority 1
associate profile 2 register NAGHI-MTP
!
dspfarm profile 2 mtp
codec g711alaw
maximum sessions hardware 25
associate application SCCP
!
dial-peer voice 802 voip
description ** SIP TO STC **
translation-profile outgoing OUT
destination-pattern 9T
session protocol sipv2
session target ipv4:10.208.9.69:5060
session transport udp
voice-class codec 1
voice-class sip dtmf-relay force rtp-nte
dtmf-relay sip-notify rtp-nte sip-kpml
no vad
!
dial-peer voice 811 voip
description ** SIP INCOMING FROM STC **
translation-profile incoming SIP-NEW
translation-profile outgoing REDIAL
destination-pattern 7...
session protocol sipv2
session target ipv4:192.168.200.53
incoming called-number 022217...$
dtmf-relay sip-notify rtp-nte sip-kpml
codec g711alaw
!
dial-peer voice 812 voip
description ** SIP INCOMING FROM STC **
translation-profile incoming SIP-NEW
translation-profile outgoing REDIAL
destination-pattern 7...
session protocol sipv2
session target ipv4:192.168.200.53
incoming called-number 22217...$
dtmf-relay sip-notify rtp-nte sip-kpml
codec g711alaw
!
dial-peer voice 813 voip
description ** SIP INCOMING FROM STC **
translation-profile incoming SIP-NEW
translation-profile outgoing REDIAL
destination-pattern 7...
session protocol sipv2
session target ipv4:192.168.200.53
incoming called-number 2217...$
dtmf-relay sip-notify rtp-nte sip-kpml
codec g711alaw
!
dial-peer voice 814 voip
description ** SIP INCOMING FROM STC **
translation-profile incoming SIP-NEW
translation-profile outgoing REDIAL
preference 1
destination-pattern 7...
session protocol sipv2
session target ipv4:192.168.200.63
incoming called-number 022217...$
dtmf-relay sip-notify rtp-nte sip-kpml
codec g711alaw
!
dial-peer voice 815 voip
description ** SIP INCOMING FROM STC **
translation-profile incoming SIP-NEW
translation-profile outgoing REDIAL
preference 1
destination-pattern 7...
session protocol sipv2
session target ipv4:192.168.200.63
incoming called-number 22217...$
dtmf-relay sip-notify rtp-nte sip-kpml
codec g711alaw
!
dial-peer voice 816 voip
description ** SIP INCOMING FROM STC **
translation-profile incoming SIP-NEW
translation-profile outgoing REDIAL
preference 1
destination-pattern 7...
session protocol sipv2
session target ipv4:192.168.200.63
incoming called-number 2217...$
dtmf-relay sip-notify rtp-nte sip-kpml
codec g711alaw

!

dial-peer voice 817 voip
description ** SIP INCOMING FROM STC **
translation-profile incoming SIP-NEW
translation-profile outgoing REDIAL
destination-pattern 7...
session protocol sipv2
session target ipv4:192.168.200.53
incoming called-number 0122217...$
dtmf-relay sip-notify rtp-nte sip-kpml
codec g711alaw
!
dial-peer voice 818 voip
description ** SIP INCOMING FROM STC **
translation-profile incoming SIP-NEW
translation-profile outgoing REDIAL
preference 1
destination-pattern 7...
session protocol sipv2
session target ipv4:192.168.200.63
incoming called-number 0122217...$
dtmf-relay sip-notify rtp-nte sip-kpml
codec g711alaw

Please i need ur help ASAP

Suresh Subramanian · ‎05-31-2014

Hi Aysar,

seems there is no inbound dial-peer from CUCM to CUBE and hence it is matching default dial-peer 0 and negotiated g729 codec in that leg. the call leg from CUBE to ITSP matched the outbound dial-peer 802 negotiated G711A law codec.

Please create separate inbound dial-peer from CUCM to CUBE and outbound dial-peer from CUBE to CUCM for other direction.

Also, you may try binding the interfaces GigabitEthernet0/0 & GigabitEthernet0/1 in those dial-peers. Please refer this on how to bind the interfaces on dial-peers.

https://supportforums.cisco.com/blog/154506

//Suresh

Please rate all the helpful posts

//Suresh Please rate all the useful posts.

aysar3000 · ‎06-18-2014

Dears

can you mention in detail which type of dial-peer you want

sorry for the late replay

Regards

Ayodeji Okanlawon · ‎06-18-2014

Aysar,

I agree with Suresh that you need to create a voip dial-peer to match inbound calls from cucm. However that is not the cause of your issue here, this could create another issue for you later on..

From the logs, your ITSP is diconnecting the call and it is most likely that they dont recognise the ip address the INVITE is coming from (172.29.7.94).

The warning 399, suggests simething is not right with this IP on their end. I sugest you contact them and find out why they are dropping the call

Received:
SIP/2.0 500 Server Internal Error

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK461C

Record-Route: <sip:10.208.9.69:5060;transport=udp;lr>

Call-ID: 8C9F6977-E76E11E3-8277BE8C-EC3B12A0@172.29.7.94

From: "Aysar Mohamed"<sip:2217156@172.29.7.94>;tag=7394E4-1898

To: <sip:0555769123@10.208.9.69>;tag=sbc0806eppk5yip-CC-57

CSeq: 101 INVITE

Reason: Q.850;cause=127;text="interworking unspecified"

Warning: 399 - "SoftX3000 R601-CCU Rel POS:[3103] Release from CR"

Content-Length: 0

You should configure an inbound dial-peer as follows (assuming you are using a sip trunk between cucm and the cube)

dial-peer voice 1 voip

incoming called number .

session protocol sipv2

no vad

voice-class codec 1

dtmf-relay rtp-nte

Please rate all useful posts

aysar3000 · ‎06-22-2014

Dears

i created the dial-peer but still no luck

there is problem in pinging the SIP Server though

there is a loose of data sometimes

when i make a call as i told u see in the debug it drop but i have to redial sometimes from 3 to 7 times before it works

Ayodeji Okanlawon · ‎06-22-2014

Have you spoken to your service provider yet? I did mention that you should speak with them.

Please rate all useful posts

aysar3000 · ‎07-14-2014

Dears sorry for the late replay

I talked to them they say change the priority PCMA dn PCMU which no need and i did and also they ask T30 which cant be disable but they are lost too

rothomas2 · ‎07-14-2014

Bad service provider returning you 500 messages back. From looking at your trace I don't see something that would cause them to return you a 500 message.

Maybe they have some balancing to several SIP servers on their side and one of them it's missconfigured who knows.

How reproducible is this?

Can you get us also a debug from a working call, so we can at least compare for diferences?

aysar3000 · ‎07-19-2014

Dears

sorry again but we were facing some other sites problem in routing the whole week

so the working call debug is this

JJC-10LW-SIP-VGW1#debu ccs
JJC-10LW-SIP-VGW1#debu ccsip me
JJC-10LW-SIP-VGW1#debu ccsip mes
JJC-10LW-SIP-VGW1#debu ccsip messages
SIP Call messages tracing is enabled
JJC-10LW-SIP-VGW1#debu voice cc
JJC-10LW-SIP-VGW1#debu voice ccapi in
JJC-10LW-SIP-VGW1#debu voice ccapi ino
JJC-10LW-SIP-VGW1#debu voice ccapi inout
voip ccapi inout debugging is on
JJC-10LW-SIP-VGW1#term mon
JJC-10LW-SIP-VGW1#term monitor
JJC-10LW-SIP-VGW1#
Jul 19 11:11:04.382: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:90565285270@192.168.200.86:5060 SIP/2.0

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cbe506ffae4

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>

Date: Sat, 19 Jul 2014 11:11:04 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

Supported: timer,resource-priority,replaces

Min-SE: 1800

User-Agent: Cisco-CUCM8.6

Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY

CSeq: 101 INVITE

Expires: 180

Allow-Events: presence, kpml

Supported: X-cisco-srtp-fallback

Supported: Geolocation

Call-Info: <sip:192.168.200.53:5060>;method="NOTIFY;Event=telephone-event;Duration=500"

Cisco-Guid: 1583913344-0000065536-0000006562-0902342848

Session-Expires: 1800

P-Asserted-Identity: "Autoline" <sip:2217187@192.168.200.53>

Remote-Party-ID: "Autoline" <sip:2217187@192.168.200.53>;party=calling;screen=yes;privacy=off

Contact: <sip:2217187@192.168.200.53:5060;transport=tcp>

Max-Forwards: 70

Content-Length: 0

Jul 19 11:11:04.386: //-1/5E6899800000/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=2217187
   ----- ccCallInfo IE subfields -----
   cisco-ani=2217187
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=1
   dest=90565285270
   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

Jul 19 11:11:04.386: //-1/5E6899800000/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x30EF0454, Call Info(
   Calling Number=2217187,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=90565285270(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=77419
Jul 19 11:11:04.386: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 19 11:11:04.386: :cc_get_feature_vsa malloc success
Jul 19 11:11:04.386: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 19 11:11:04.386: cc_get_feature_vsa count is 1
Jul 19 11:11:04.386: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 19 11:11:04.386: :FEATURE_VSA attributes are: feature_name:0,feature_time:805321432,feature_id:33227
Jul 19 11:11:04.386: //77419/5E6899800000/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=2217187(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=90565285270(TON=Unknown, NPI=Unknown))
Jul 19 11:11:04.386: //77419/5E6899800000/CCAPI/cc_process_call_setup_ind:
   Event=0x30FFEC88
Jul 19 11:11:04.386: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 90565285270
Jul 19 11:11:04.386: //77419/5E6899800000/CCAPI/ccCallSetContext:
   Context=0x2C11C24C
Jul 19 11:11:04.386: //77419/5E6899800000/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 77419 with tag 0 to app "_ManagedAppProcess_Default"
Jul 19 11:11:04.386: //77419/5E6899800000/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
Jul 19 11:11:04.386: //77419/5E6899800000/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=TRUE, Mode=0,
   Outgoing Dial-peer=802, Params=0x2C1107DC, Progress Indication=NULL(0)
Jul 19 11:11:04.390: //77419/5E6899800000/CCAPI/ccCheckClipClir:
   In: Calling Number=2217187(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
Jul 19 11:11:04.390: //77419/5E6899800000/CCAPI/ccCheckClipClir:
   Out: Calling Number=2217187(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
Jul 19 11:11:04.390: //77419/5E6899800000/CCAPI/ccCallSetupRequest:
   Destination Pattern=9T, Called Number=0565285270, Digit Strip=FALSE
Jul 19 11:11:04.390: //77419/5E6899800000/CCAPI/ccCallSetupRequest:
   Calling Number=2217187(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=0565285270(TON=Unknown, NPI=Unknown),
   Redirect Number=, Display Info=Autoline
   Account Number=2217187, Final Destination Flag=TRUE,
   Guid=5E689980-0001-0000-0000-19A235C8A8C0, Outgoing Dial-peer=802
Jul 19 11:11:04.390: //77419/5E6899800000/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=2217187
   ----- ccCallInfo IE subfields -----
   cisco-ani=2217187
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=1
   dest=0565285270
   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

Jul 19 11:11:04.390: //77419/5E6899800000/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x30EF0454, Interface Type=3, Destination=, Mode=0x0,
   Call Params(Calling Number=2217187,(Calling Name=Autoline)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=0565285270(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=802, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
Jul 19 11:11:04.390: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 19 11:11:04.390: :cc_get_feature_vsa malloc success
Jul 19 11:11:04.390: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 19 11:11:04.390: cc_get_feature_vsa count is 2
Jul 19 11:11:04.390: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 19 11:11:04.390: :FEATURE_VSA attributes are: feature_name:0,feature_time:805320088,feature_id:33228
Jul 19 11:11:04.390: //77420/5E6899800000/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
Jul 19 11:11:04.390: //77420/5E6899800000/CCAPI/ccCallSetContext:
   Context=0x2C11078C
Jul 19 11:11:04.390: //77419/5E6899800000/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=802
Jul 19 11:11:04.390: //77420/5E6899800000/CCAPI/cc_api_call_proceeding:
   Interface=0x30EF0454, Progress Indication=NULL(0)
Jul 19 11:11:04.390: //77420/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:0565285270@10.208.9.69:5060 SIP/2.0

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK9676AEE

Remote-Party-ID: "Autoline" <sip:2217187@172.29.7.94>;party=calling;screen=yes;privacy=off

From: "Autoline" <sip:2217187@172.29.7.94>;tag=34373428-3A8

To: <sip:0565285270@10.208.9.69>

Date: Sat, 19 Jul 2014 11:11:04 GMT

Call-ID: 35726640-E6C11E4-AF7485AD-E4E15E65@172.29.7.94

Supported: timer,resource-priority,replaces,sdp-anat

Min-SE: 1800

Cisco-Guid: 1583913344-0000065536-0000006562-0902342848

User-Agent: Cisco-SIPGateway/IOS-12.x

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Timestamp: 1405768264

Contact: <sip:2217187@172.29.7.94:5060>

Call-Info: <sip:172.29.7.94:5060>;method="NOTIFY;Event=telephone-event;Duration=2000"

Expires: 180

Allow-Events: kpml, telephone-event

Max-Forwards: 69

Session-Expires: 1800

Content-Length: 0

Jul 19 11:11:04.390: //77419/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cbe506ffae4

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>

Date: Sat, 19 Jul 2014 11:11:04 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0

Jul 19 11:11:04.406: //77420/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK9676AEE

Call-ID: 35726640-E6C11E4-AF7485AD-E4E15E65@172.29.7.94

From: "Autoline"<sip:2217187@172.29.7.94>;tag=34373428-3A8

To: <sip:0565285270@10.208.9.69>

CSeq: 101 INVITE

Content-Length: 0

Jul 19 11:11:08.386: //77420/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK9676AEE

Record-Route: <sip:10.208.9.69:5060;transport=udp;lr>

Call-ID: 35726640-E6C11E4-AF7485AD-E4E15E65@172.29.7.94

From: "Autoline"<sip:2217187@172.29.7.94>;tag=34373428-3A8

To: <sip:0565285270@10.208.9.69>;tag=sbc0802gfkiogsi-CC-60

CSeq: 101 INVITE

Contact: <sip:0565285270@10.208.9.69:5060;user=phone>

Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER

Content-Length: 369

Content-Type: application/sdp

v=0

o=- 36781639 36781639 IN IP4 10.208.9.69

s=SBC call

c=IN IP4 10.208.9.69

t=0 0

m=audio 54900 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

Jul 19 11:11:08.386: //77420/5E6899800000/CCAPI/cc_api_caps_ind:
   Destination Interface=0x0, Destination Call Id=-1, Source Call Id=77420,
   Caps(Codec=0x2, Fax Rate=0x2, Vad=0x1,
   Modem=0x0, Codec Bytes=160, Signal Type=2)
Jul 19 11:11:08.386: //77420/5E6899800000/CCAPI/cc_api_caps_ind:
   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
   Playout Max=1000(ms), Fax Nom=300(ms))
Jul 19 11:11:08.386: //77419/5E6899800000/CCAPI/cc_api_caps_ack:
   Destination Interface=0x0, Destination Call Id=-1, Source Call Id=77419,
   Caps(Codec=g729r8(0x4), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),
   Modem=OFF(0x0), Codec Bytes=20, Signal Type=2, Seq Num Start=2920)
Jul 19 11:11:08.386: //77419/5E6899800000/CCAPI/cc_api_caps_ack:
   Destination Interface=0x0, Destination Call Id=-1, Source Call Id=77419,
   Caps(Codec=g729r8(0x4), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),
   Modem=OFF(0x0), Codec Bytes=20, Signal Type=2, Seq Num Start=2920)
Jul 19 11:11:08.386: //77420/5E6899800000/CCAPI/cc_api_event_indication:
   Event=170, Call Id=77420
Jul 19 11:11:08.386: //77420/5E6899800000/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
Jul 19 11:11:08.386: //77420/5E6899800000/CCAPI/cc_api_call_alert:
   Interface=0x30EF0454, Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1)
Jul 19 11:11:08.386: //77420/5E6899800000/CCAPI/cc_api_call_alert:
   Call Entry(Retry Count=0, Responsed=TRUE)
Jul 19 11:11:08.390: //77419/5E6899800000/CCAPI/ccCallAlert:
   Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1)
Jul 19 11:11:08.390: //77419/5E6899800000/CCAPI/ccCallAlert:
   Call Entry(Responsed=TRUE, Alert Sent=TRUE)
Jul 19 11:11:08.390: //77419/5E6899800000/CCAPI/ccConferenceCreate:
   (confID=0x31DE99E4, callID1=0x12E6B, gcid=3571C97F-E6C11E4-AF7185AD-E4E15E65, tag=0x0)
Jul 19 11:11:08.390: //77420/5E6899800000/CCAPI/ccConferenceCreate:
   (confID=0x31DE99E4, callID2=0x12E6C, gcid=3571C97F-E6C11E4-AF7185AD-E4E15E65, tag=0x0)
Jul 19 11:11:08.390: //77419/5E6899800000/CCAPI/ccConferenceCreate:
   Conference Id=0x31DE99E4, Call Id1=77419, Call Id2=77420, Tag=0x0
Jul 19 11:11:08.390: //77419/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Jul 19 11:11:08.390: cc_api_get_xcode_stream : 4702
Jul 19 11:11:08.390: //77420/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Jul 19 11:11:08.390: cc_api_get_xcode_stream : 4702
Jul 19 11:11:08.390: //77419/5E6899800000/CCAPI/ccConferenceCreate:

Jul 19 11:11:08.390: ccConferenceCreate: ret1=0, codecMask1=2, bytes1=160, negot1=0, dtmf1=0
                    ret2=0, codecMask2=2, bytes2=160, negot2=1, dtmf2=6,
                    tx_dynamic_pt1=0, rx_dynamic_pt1=0, codec_mode1=0, params_bitmap1 =0
                    tx_dynamic_pt2=8, rx_dynamic_pt2=8, codec_mode2=0, params_bitmap2 =0
Jul 19 11:11:08.390: //77419/5E6899800000/CCAPI/ccConferenceCreate:
   delay media to slow start case, codec negotation is not done
Jul 19 11:11:08.390: //77419/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Jul 19 11:11:08.390: cc_api_get_xcode_stream : 4702
Jul 19 11:11:08.390: //77419/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Jul 19 11:11:08.390: cc_api_get_xcode_stream : 4702
Jul 19 11:11:08.390: //77419/5E6899800000/CCAPI/cc_api_bridge_done:
   Conference Id=0x1DE1, Source Interface=0x30EF0454, Source Call Id=77419,
   Destination Call Id=77420, Disposition=0x0, Tag=0x0
Jul 19 11:11:08.390: //77420/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Jul 19 11:11:08.390: cc_api_get_xcode_stream : 4702
Jul 19 11:11:08.390: //77420/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Jul 19 11:11:08.390: cc_api_get_xcode_stream : 4702
Jul 19 11:11:08.390: //77420/5E6899800000/CCAPI/cc_api_bridge_done:
   Conference Id=0x1DE1, Source Interface=0x30EF0454, Source Call Id=77420,
   Destination Call Id=77419, Disposition=0x0, Tag=0x0
Jul 19 11:11:08.390: //77419/5E6899800000/CCAPI/cc_generic_bridge_done:
   Conference Id=0x1DE1, Source Interface=0x30EF0454, Source Call Id=77420,
   Destination Call Id=77419, Disposition=0x0, Tag=0x0
Jul 19 11:11:08.390: //77419/5E6899800000/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x1DE1, Destination Call Id=77420)
Jul 19 11:11:08.390: //77420/5E6899800000/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x1DE1, Destination Call Id=77419)
Jul 19 11:11:08.390: //77419/5E6899800000/CCAPI/cc_process_notify_bridge_done:
   Conference Id=0x1DE1, Call Id1=77419, Call Id2=77420
Jul 19 11:11:08.390: //77419/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 183 Session Progress

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cbe506ffae4

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Date: Sat, 19 Jul 2014 11:11:04 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 101 INVITE

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Remote-Party-ID: <sip:0565285270@192.168.200.86>;party=called;screen=no;privacy=off

Contact: <sip:0565285270@192.168.200.86:5060;transport=tcp>

Supported: sdp-anat

Server: Cisco-SIPGateway/IOS-12.x

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 280

v=0

o=CiscoSystemsSIP-GW-UserAgent 7427 4964 IN IP4 192.168.200.86

s=SIP Call

c=IN IP4 192.168.200.86

t=0 0

m=audio 17956 RTP/AVP 8 0 18 19

c=IN IP4 192.168.200.86

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:19 CN/8000

Jul 19 11:11:09.806: //77420/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK9676AEE

Record-Route: <sip:10.208.9.69:5060;transport=udp;lr>

Call-ID: 35726640-E6C11E4-AF7485AD-E4E15E65@172.29.7.94

From: "Autoline"<sip:2217187@172.29.7.94>;tag=34373428-3A8

To: <sip:0565285270@10.208.9.69>;tag=sbc0802gfkiogsi-CC-60

CSeq: 101 INVITE

Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER

Contact: <sip:0565285270@10.208.9.69:5060;user=phone>

Content-Length: 369

Content-Type: application/sdp

v=0

o=- 36781639 36781640 IN IP4 10.208.9.69

s=SBC call

c=IN IP4 10.208.9.69

t=0 0

m=audio 54900 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

Jul 19 11:11:13.546: //77420/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK9676AEE

Record-Route: <sip:10.208.9.69:5060;transport=udp;lr>

Call-ID: 35726640-E6C11E4-AF7485AD-E4E15E65@172.29.7.94

From: "Autoline"<sip:2217187@172.29.7.94>;tag=34373428-3A8

To: <sip:0565285270@10.208.9.69>;tag=sbc0802gfkiogsi-CC-60

CSeq: 101 INVITE

Contact: <sip:0565285270@10.208.9.69:5060;user=phone>

Content-Length: 369

Content-Type: application/sdp

v=0

o=- 36781639 36781641 IN IP4 10.208.9.69

s=SBC call

c=IN IP4 10.208.9.69

t=0 0

m=audio 54900 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

Jul 19 11:11:13.546: //77420/5E6899800000/CCAPI/cc_api_call_connected:
   Interface=0x30EF0454, Data Bitmask=0x1, Progress Indication=NULL(0),
   Connection Handle=0
Jul 19 11:11:13.546: //77420/5E6899800000/CCAPI/cc_api_call_connected:
   Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
Jul 19 11:11:13.546: //77419/5E6899800000/CCAPI/ccCallConnect:
   Progress Indication=NULL(0), Data Bitmask=0x1
Jul 19 11:11:13.546: //77419/5E6899800000/CCAPI/ccCallConnect:
   Call Entry(Connected=TRUE, Responsed=TRUE)
Jul 19 11:11:13.550: //77419/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cbe506ffae4

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Date: Sat, 19 Jul 2014 11:11:04 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 101 INVITE

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Remote-Party-ID: <sip:0565285270@192.168.200.86>;party=called;screen=no;privacy=off

Contact: <sip:90565285270@192.168.200.86:5060;transport=tcp>

Supported: replaces

Supported: sdp-anat

Server: Cisco-SIPGateway/IOS-12.x

Supported: timer

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 280

v=0

o=CiscoSystemsSIP-GW-UserAgent 7427 4964 IN IP4 192.168.200.86

s=SIP Call

c=IN IP4 192.168.200.86

t=0 0

m=audio 17956 RTP/AVP 8 0 18 19

c=IN IP4 192.168.200.86

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:19 CN/8000

Jul 19 11:11:13.786: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:90565285270@192.168.200.86:5060;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cbf3ee8f0d9

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Date: Sat, 19 Jul 2014 11:11:04 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: presence, kpml

Content-Type: application/sdp

Content-Length: 160

v=0

o=CiscoSystemsCCM-SIP 147550 1 IN IP4 192.168.200.53

s=SIP Call

c=IN IP4 172.18.2.205

t=0 0

m=audio 20872 RTP/AVP 0

a=rtpmap:0 PCMU/8000

a=ptime:20

Jul 19 11:11:13.786: //77419/5E6899800000/CCAPI/cc_api_caps_ind:
   Destination Interface=0x30EF0454, Destination Call Id=77420, Source Call Id=77419,
   Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2,
   Modem=0x0, Codec Bytes=20, Signal Type=2)
Jul 19 11:11:13.786: //77419/5E6899800000/CCAPI/cc_api_caps_ind:
   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
   Playout Max=1000(ms), Fax Nom=300(ms))
Jul 19 11:11:13.786: //77420/5E6899800000/CCAPI/cc_api_caps_ack:
   Destination Interface=0x30EF0454, Destination Call Id=77419, Source Call Id=77420,
   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
   Modem=OFF(0x0), Codec Bytes=20, Signal Type=2, Seq Num Start=3288)
Jul 19 11:11:13.786: //77420/5E6899800000/CCAPI/cc_api_caps_ack:
   Destination Interface=0x30EF0454, Destination Call Id=77419, Source Call Id=77420,
   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
   Modem=OFF(0x0), Codec Bytes=20, Signal Type=2, Seq Num Start=3288)
Jul 19 11:11:13.786: //77419/5E6899800000/CCAPI/cc_api_event_indication:
   Event=171, Call Id=77419
Jul 19 11:11:13.786: //77419/5E6899800000/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
Jul 19 11:11:13.790: //77420/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:0565285270@10.208.9.69:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK967815FB

From: "Autoline" <sip:2217187@172.29.7.94>;tag=34373428-3A8

To: <sip:0565285270@10.208.9.69>;tag=sbc0802gfkiogsi-CC-60

Date: Sat, 19 Jul 2014 11:11:04 GMT

Call-ID: 35726640-E6C11E4-AF7485AD-E4E15E65@172.29.7.94

Route: <sip:10.208.9.69:5060;transport=udp;lr>

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: kpml, telephone-event

Content-Type: application/sdp

Content-Length: 228

v=0

o=CiscoSystemsSIP-GW-UserAgent 216 1335 IN IP4 172.29.7.94

s=SIP Call

c=IN IP4 172.29.7.94

t=0 0

m=audio 16570 RTP/AVP 0 97

c=IN IP4 172.29.7.94

a=rtpmap:0 PCMU/8000

a=rtpmap:97 telephone-event/8000

a=fmtp:97 0-16

Jul 19 11:11:13.790: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SUBSCRIBE sip:2217187@192.168.200.53:5060;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 192.168.200.86:5060;branch=z9hG4bK9677516

From: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

To: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 101 SUBSCRIBE

Max-Forwards: 70

Date: Sat, 19 Jul 2014 11:11:13 GMT

User-Agent: Cisco-SIPGateway/IOS-12.x

Event: kpml

Expires: 7200

Contact: <sip:192.168.200.86:5060;transport=tcp>

Content-Type: application/kpml-request+xml

Content-Length: 327

<?xml version="1.0" encoding="UTF-8"?><kpml-request xmlns="urn:ietf:params:xml:ns:kpml-request" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="urn:ietf:params:xml:ns:kpml-request kpml-request.xsd" version="1.0"><pattern persist="persist"><regex tag="dtmf">[x*#ABCD]</regex></pattern></kpml-request>

Jul 19 11:11:13.790: //0/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK

Via: SIP/2.0/TCP 192.168.200.86:5060;branch=z9hG4bK9677516

From: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

To: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

Date: Sat, 19 Jul 2014 11:11:13 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 101 SUBSCRIBE

Contact: <sip:2217187@192.168.200.53:5060;transport=tcp>

Expires: 7200

Content-Length: 0

Jul 19 11:11:13.790: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:192.168.200.86:5060;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cc05a8a91e9

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 102 NOTIFY

Max-Forwards: 70

Date: Sat, 19 Jul 2014 11:11:13 GMT

User-Agent: Cisco-CUCM8.6

Event: kpml

Subscription-State: active;expires=7200

Contact: <sip:2217187@192.168.200.53:5060;transport=tcp>

P-Asserted-Identity: "Autoline" <sip:2217187@192.168.200.53>

Content-Length: 0

Jul 19 11:11:13.794: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cc05a8a91e9

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Date: Sat, 19 Jul 2014 11:11:13 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 102 NOTIFY

Content-Length: 0

Jul 19 11:11:13.846: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:2217187@172.29.7.94:5060 SIP/2.0

Via: SIP/2.0/UDP 10.208.9.69:5060;branch=z9hG4bKs1p3pfcpmisth3geekgospc1yT32749

Call-ID: 35726640-E6C11E4-AF7485AD-E4E15E65@172.29.7.94

From: <sip:0565285270@10.208.9.69>;tag=sbc0802gfkiogsi-CC-60

To: "Autoline"<sip:2217187@172.29.7.94>;tag=34373428-3A8

CSeq: 1 OPTIONS

Accept: application/sdp

Max-Forwards: 70

Content-Length: 0

Jul 19 11:11:13.846: //77420/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.208.9.69:5060;branch=z9hG4bKs1p3pfcpmisth3geekgospc1yT32749

From: <sip:0565285270@10.208.9.69>;tag=sbc0802gfkiogsi-CC-60

To: "Autoline" <sip:2217187@172.29.7.94>;tag=34373428-3A8

Date: Sat, 19 Jul 2014 11:11:13 GMT

Call-ID: 35726640-E6C11E4-AF7485AD-E4E15E65@172.29.7.94

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 1 OPTIONS

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: kpml, telephone-event

Accept: application/sdp

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Content-Type: application/sdp

Content-Length: 515

v=0

o=CiscoSystemsSIP-GW-UserAgent 216 1335 IN IP4 172.29.7.94

s=SIP Call

c=IN IP4 172.29.7.94

t=0 0

m=audio 16570 RTP/AVP 0 97

c=IN IP4 172.29.7.94

a=rtpmap:0 PCMU/8000

a=rtpmap:97 telephone-event/8000

a=fmtp:97 0-16

m=image 16570 udptl t38

c=IN IP4 172.29.7.94

a=T38FaxVersion:0

a=T38MaxBitRate:14400

a=T38FaxFillBitRemoval:0

a=T38FaxTranscodingMMR:0

a=T38FaxTranscodingJBIG:0

a=T38FaxRateManagement:transferredTCF

a=T38FaxMaxBuffer:200

a=T38FaxMaxDatagram:320

a=T38FaxUdpEC:t38UDPRedundancy

Jul 19 11:11:15.690: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:172.29.7.94:5060 SIP/2.0

Via: SIP/2.0/UDP 10.208.9.69:5060;branch=z9hG4bKhicckiyt3fomyimfttkfkpepgT30948

Call-ID: isbc15hiyy31gk1gthmsh55cig1osceh3yo3@SoftX3000

From: <sip:172.29.7.94:5060>;tag=sbc0804y355th3o

To: <sip:172.29.7.94>

CSeq: 1 OPTIONS

Max-Forwards: 70

Content-Length: 0

Jul 19 11:11:15.694: //77421/3C2EA23AAF75/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.208.9.69:5060;branch=z9hG4bKhicckiyt3fomyimfttkfkpepgT30948

From: <sip:172.29.7.94:5060>;tag=sbc0804y355th3o

To: <sip:172.29.7.94>;tag=3437604C-262

Date: Sat, 19 Jul 2014 11:11:15 GMT

Call-ID: isbc15hiyy31gk1gthmsh55cig1osceh3yo3@SoftX3000

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 1 OPTIONS

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Accept: application/sdp

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Content-Type: application/sdp

Content-Length: 446

v=0

o=CiscoSystemsSIP-GW-UserAgent 3839 6748 IN IP4 172.29.7.94

s=SIP Call

c=IN IP4 172.29.7.94

t=0 0

m=audio 0 RTP/AVP 18 0 8 9 4 2 15

c=IN IP4 172.29.7.94

m=image 0 udptl t38

c=IN IP4 172.29.7.94

a=T38FaxVersion:0

a=T38MaxBitRate:9600

a=T38FaxFillBitRemoval:0

a=T38FaxTranscodingMMR:0

a=T38FaxTranscodingJBIG:0

a=T38FaxRateManagement:transferredTCF

a=T38FaxMaxBuffer:200

a=T38FaxMaxDatagram:320

a=T38FaxUdpEC:t38UDPRedundancy

Jul 19 11:11:19.210: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:90565285270@192.168.200.86:5060;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cc118f5b886

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Date: Sat, 19 Jul 2014 11:11:04 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

User-Agent: Cisco-CUCM8.6

Max-Forwards: 70

P-Asserted-Identity: "Autoline" <sip:2217187@192.168.200.53>

CSeq: 103 BYE

Reason: Q.850;cause=16

Content-Length: 0

Jul 19 11:11:19.210: //77420/5E6899800000/CCAPI/ccGenerateToneInfo:
   Stop Tone On Digit=FALSE, Tone=Null,
   Tone Direction=Sum Network, Params=0x0, Call Id=77420
Jul 19 11:11:19.210: //77419/5E6899800000/CCAPI/cc_api_call_disconnected:
   Cause Value=16, Interface=0x30EF0454, Call Id=77419
Jul 19 11:11:19.210: //77419/5E6899800000/CCAPI/cc_api_call_disconnected:
   Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
Jul 19 11:11:19.210: //77419/5E6899800000/CCAPI/ccConferenceDestroy:
   Conference Id=0x1DE1, Tag=0x0
Jul 19 11:11:19.210: //77419/5E6899800000/CCAPI/cc_api_bridge_drop_done:
   Conference Id=0x1DE1, Source Interface=0x30EF0454, Source Call Id=77419,
   Destination Call Id=77420, Disposition=0x0, Tag=0x0
Jul 19 11:11:19.210: //77420/5E6899800000/CCAPI/cc_api_bridge_drop_done:
   Conference Id=0x1DE1, Source Interface=0x30EF0454, Source Call Id=77420,
   Destination Call Id=77419, Disposition=0x0, Tag=0x0
Jul 19 11:11:19.214: //77419/5E6899800000/CCAPI/cc_generic_bridge_done:
   Conference Id=0x1DE1, Source Interface=0x30EF0454, Source Call Id=77420,
   Destination Call Id=77419, Disposition=0x0, Tag=0x0
Jul 19 11:11:19.214: //77419/5E6899800000/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
Jul 19 11:11:19.214: //77419/5E6899800000/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
Jul 19 11:11:19.214: //77420/5E6899800000/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Jul 19 11:11:19.214: //77420/5E6899800000/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
Jul 19 11:11:19.214: //77419/5E6899800000/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x30EF0454, Tag=0x0, Call Id=77419,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
Jul 19 11:11:19.214: //77419/5E6899800000/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Jul 19 11:11:19.214: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 19 11:11:19.214: :cc_free_feature_vsa freeing 30003AD0
Jul 19 11:11:19.214: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 19 11:11:19.214: vsacount in free is 1
Jul 19 11:11:19.214: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cc118f5b886

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Date: Sat, 19 Jul 2014 11:11:19 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 103 BYE

Reason: Q.850;cause=16

P-RTP-Stat: PS=265,OS=42400,PR=264,OR=42240,PL=0,JI=0,LA=0,DU=5

Content-Length: 0

Jul 19 11:11:19.214: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SUBSCRIBE sip:2217187@192.168.200.53:5060;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 192.168.200.86:5060;branch=z9hG4bK967A168B

From: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

To: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 102 SUBSCRIBE

Max-Forwards: 70

Date: Sat, 19 Jul 2014 11:11:19 GMT

User-Agent: Cisco-SIPGateway/IOS-12.x

Event: kpml

Expires: 0

Contact: <sip:192.168.200.86:5060;transport=tcp>

Content-Length: 0

Jul 19 11:11:19.214: //77420/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:0565285270@10.208.9.69:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK96792084

From: "Autoline" <sip:2217187@172.29.7.94>;tag=34373428-3A8

To: <sip:0565285270@10.208.9.69>;tag=sbc0802gfkiogsi-CC-60

Date: Sat, 19 Jul 2014 11:11:13 GMT

Call-ID: 35726640-E6C11E4-AF7485AD-E4E15E65@172.29.7.94

User-Agent: Cisco-SIPGateway/IOS-12.x

Max-Forwards: 70

Route: <sip:10.208.9.69:5060;transport=udp;lr>

Timestamp: 1405768279

CSeq: 102 BYE

Reason: Q.850;cause=16

P-RTP-Stat: PS=264,OS=42240,PR=265,OR=42400,PL=0,JI=0,LA=0,DU=5

Content-Length: 0

Jul 19 11:11:19.218: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:192.168.200.86:5060;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cc2258418e3

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 104 NOTIFY

Max-Forwards: 70

Date: Sat, 19 Jul 2014 11:11:19 GMT

User-Agent: Cisco-CUCM8.6

Event: kpml

Subscription-State: terminated

Contact: <sip:2217187@192.168.200.53:5060;transport=tcp>

P-Asserted-Identity: "Autoline" <sip:2217187@192.168.200.53>

Content-Length: 0

Jul 19 11:11:19.218: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cc2258418e3

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Date: Sat, 19 Jul 2014 11:11:19 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 104 NOTIFY

Content-Length: 0

Jul 19 11:11:19.222: //0/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK

Via: SIP/2.0/TCP 192.168.200.86:5060;branch=z9hG4bK967A168B

From: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

To: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

Date: Sat, 19 Jul 2014 11:11:19 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 102 SUBSCRIBE

Contact: <sip:2217187@192.168.200.53:5060;transport=tcp>

Expires: 0

Content-Length: 0

Jul 19 11:11:19.222: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:192.168.200.86:5060;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cc3468e2973

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 105 NOTIFY

Max-Forwards: 70

Date: Sat, 19 Jul 2014 11:11:19 GMT

User-Agent: Cisco-CUCM8.6

Event: kpml

Subscription-State: terminated;reason=timeout

Contact: <sip:2217187@192.168.200.53:5060;transport=tcp>

P-Asserted-Identity: "Autoline" <sip:2217187@192.168.200.53>

Content-Type: application/kpml-response+xml

Content-Length: 348

<?xml version="1.0" encoding="UTF-8" ?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="urn:ietf:params:xml:ns:kpml-response kpml-response.xsd" code="487" digits="" forced_flush="false" suppressed="false" tag="dtmf" text="Subscription Expired" version="1.0"/>

Jul 19 11:11:19.222: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 481 Subscription does not exist

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cc3468e2973

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Date: Sat, 19 Jul 2014 11:11:19 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

CSeq: 105 NOTIFY

Content-Length: 0

Jul 19 11:11:19.338: //77420/5E6899800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.29.7.94:5060;branch=z9hG4bK96792084

Call-ID: 35726640-E6C11E4-AF7485AD-E4E15E65@172.29.7.94

From: "Autoline"<sip:2217187@172.29.7.94>;tag=34373428-3A8

To: <sip:0565285270@10.208.9.69>;tag=sbc0802gfkiogsi-CC-60

CSeq: 102 BYE

Content-Length: 0

Jul 19 11:11:19.338: //77420/5E6899800000/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x30EF0454, Tag=0x0, Call Id=77420,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
Jul 19 11:11:19.338: //77420/5E6899800000/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Jul 19 11:11:19.338: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 19 11:11:19.338: :cc_free_feature_vsa freeing 30003590
Jul 19 11:11:19.338: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 19 11:11:19.338: vsacount in free is 0
JJC-10LW-SIP-VGW1#
JJC-10LW-SIP-VGW1#u all
All possible debugging has been turned off
JJC-10LW-SIP-VGW1#

Thanks in Advanced

Ayodeji Okanlawon · ‎07-29-2014

Everything looks okay now..This call was disconnected by the user on cucm.

What is the problem you are having now?

Received:
BYE sip:90565285270@192.168.200.86:5060;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 192.168.200.53:5060;branch=z9hG4bK7cc118f5b886

From: "Autoline" <sip:2217187@192.168.200.53>;tag=147550~244641b0-36ac-434c-91c1-823f25a68b28-32840830

To: <sip:90565285270@192.168.200.86>;tag=343743C8-1C28

Date: Sat, 19 Jul 2014 11:11:04 GMT

Call-ID: 5e689980-3ca15248-203e-35c8a8c0@192.168.200.53

User-Agent

: Cisco-CUCM8.6

Max-Forwards: 70

P-Asserted-Identity: "Autoline" <sip:2217187@192.168.200.53>

CSeq: 103 BYE

Please rate all useful posts

aysar3000 · ‎07-29-2014

Yeah i know

you asked me in pervious replay to send you a succeful call which is this one and i hang up

the 90565285270 is my mobile

you asked me to send so you compare between the successfull call and the wrong call

islam.kamal · ‎07-29-2014

Hi

Can you please find the below:-

i think you get bye message because no bind .

voice service voip

ip address trusted list

sip

bind all source-interface gig0/0

Thanks

please rate all useful information

Ayodeji Okanlawon · ‎07-30-2014

Islam,

I am afraid that is not a good suggestion. You need to understand his setup before you can even attemtp to bind all to a singe interface! What if he has multiple interfaces? This can break a lot of things..Please refer to my blog here on understanding sip binds commands.

https://supportforums.cisco.com/blog/154506

Please rate all useful posts

islam.kamal · ‎07-30-2014

Hi

+5 , yes i saw that logs is ok no issues that is why i thought he has to bind , sorry and thanks for your information.

Thanks

Ayodeji Okanlawon · ‎07-30-2014

You need to speak to your provider as I told you earlier and find out why they are terminating your call..

Please rate all useful posts