incomming calls failed

[HeribertoVV]

Hello,

I've been having an issue with incomming calls. I am able to make calls from CUCM, but not having success with incoming calls.

Call flow:

IPphone - SCCP - CUCM - SIP Trunk - Gateway - ISTP.

This is the debug:

Interface=0x2244B4B8, Interface Type=3, Destination=, Mode=0x0,
Call Params(Calling Number=8123578219,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=1990(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=1999, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
001082: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001083: Jun 18 10:52:17.257: :cc_get_feature_vsa malloc success
001084: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001085: Jun 18 10:52:17.257: cc_get_feature_vsa count is 2
001086: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001087: Jun 18 10:52:17.257: :FEATURE_VSA attributes are: feature_name:0,feature_time:1014738552,feature_id:998
001088: Jun 18 10:52:17.257: //543/779D625585A7/CCAPI/cc_api_event_indication:
Event=101, Call Id=543
001089: Jun 18 10:52:17.257: //543/779D625585A7/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
001090: Jun 18 10:52:17.257: //544/779D625585A7/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
001091: Jun 18 10:52:17.257: //544/779D625585A7/CCAPI/ccCallSetContext:
Context=0x23ED65B8
001092: Jun 18 10:52:17.257: //543/779D625585A7/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=1999
001093: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_find_feature_vsa:

001094: Jun 18 10:52:17.257: inside while in find feature_sa
001095: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_find_feature_vsa:

001096: Jun 18 10:52:17.257: Can not find, featurevsa does not exist !!!!
001097: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001098: Jun 18 10:52:17.257: :cc_get_feature_vsa malloc success
001099: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001100: Jun 18 10:52:17.257: cc_get_feature_vsa count is 3
001101: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001102: Jun 18 10:52:17.257: :FEATURE_VSA attributes are: feature_name:0,feature_time:1014739448,feature_id:999
001103: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001104: Jun 18 10:52:17.257: feature_vsa is 3C7BB2D0,featname is 0
001105: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001106: Jun 18 10:52:17.257: :cc_get_feature_vsa malloc success
001107: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001108: Jun 18 10:52:17.257: cc_get_feature_vsa count is 4
001109: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001110: Jun 18 10:52:17.257: :FEATURE_VSA attributes are: feature_name:0,feature_time:1014737656,feature_id:1000
001111: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001112: Jun 18 10:52:17.257: : Generating new feature vsa,inserting it
001113: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001114: Jun 18 10:52:17.257: feature call forward featname is 13
001115: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001116: Jun 18 10:52:17.257: feature call forward featname is 13
001117: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001118: Jun 18 10:52:17.257: feature_vsa is 3C7BAE70,featname is 0
001119: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001120: Jun 18 10:52:17.257: :cc_get_feature_vsa malloc success
001121: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001122: Jun 18 10:52:17.257: cc_get_feature_vsa count is 5
001123: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001124: Jun 18 10:52:17.257: :FEATURE_VSA attributes are: feature_name:0,feature_time:1014738104,feature_id:1001
001125: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001126: Jun 18 10:52:17.257: : Generating new feature vsa,inserting it
001127: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001128: Jun 18 10:52:17.257: feature call forward featname is 13
001129: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001130: Jun 18 10:52:17.257: :cc_free_feature_vsa freeing 3C7BB1F0
001131: Jun 18 10:52:17.257: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001132: Jun 18 10:52:17.257: vsacount in free is 4
001133: Jun 18 10:52:17.257: //544/779D625585A7/CCAPI/ccGetMediaClassTag:
media class tag 0
001134: Jun 18 10:52:17.257: //544/779D625585A7/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
001135: Jun 18 10:52:17.257: //543/779D625585A7/CCAPI/ccGetMediaClassTag:
media class tag 0
001136: Jun 18 10:52:17.257: //543/779D625585A7/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
001137: Jun 18 10:52:17.257: //544/779D625585A7/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
001138: Jun 18 10:52:17.261: //543/779D625585A7/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
001139: Jun 18 10:52:17.261: //544/779D625585A7/CCAPI/cc_api_event_indication:
Event=193, Call Id=544
001140: Jun 18 10:52:17.261: //544/779D625585A7/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
001141: Jun 18 10:52:17.261: //543/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001142: Jun 18 10:52:17.261: cc_api_get_xcode_stream : 4982
001143: Jun 18 10:52:17.261: //544/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001144: Jun 18 10:52:17.261: cc_api_get_xcode_stream : 4982
001145: Jun 18 10:52:17.261: //543/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001146: Jun 18 10:52:17.261: cc_api_get_xcode_stream : 4982
001147: Jun 18 10:52:17.261: //543/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001148: Jun 18 10:52:17.261: cc_api_get_xcode_stream : 4982
001149: Jun 18 10:52:17.261: //544/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001150: Jun 18 10:52:17.261: cc_api_get_xcode_stream : 4982
001151: Jun 18 10:52:17.261: //543/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001152: Jun 18 10:52:17.261: cc_api_get_xcode_stream : 4982
001153: Jun 18 10:52:17.261: //544/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001154: Jun 18 10:52:17.261: cc_api_get_xcode_stream : 4982
001155: Jun 18 10:52:17.261: //544/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001156: Jun 18 10:52:17.261: cc_api_get_xcode_stream : 4982
001157: Jun 18 10:52:17.261: //544/779D625585A7/CCAPI/cc_api_call_proceeding:
Interface=0x2244B4B8, Progress Indication=NULL(0)
001158: Jun 18 10:52:17.265: //543/779D625585A7/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.186.15:5060;branch=z9hG4bKtak5jh2088d0ps0kl5n1.1
From: <sip:8123578219@192.168.151.229;user=phone>;tag=snl_0008483608_NSN_CLIENT
To: <sip:8182203550@192.168.151.135;user=phone>
Date: Sat, 18 Jun 2016 15:52:17 GMT
Call-ID: NSNSIP-6596a8c0-c096a8c0-2-21-1466265137-203486-1466468623
CSeq: 1235 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.4.1.T3
Content-Length: 0

001159: Jun 18 15:52:17.265: %VOICE_IEC-3-GW: SIP: Internal Error (Socket error): IEC=1.1.186.7.7.4 on callID 544 GUID=779D625534A311E685A793B5B9697DD2
001160: Jun 18 10:52:17.265: //544/779D625585A7/CCAPI/cc_api_call_disconnected:
Cause Value=38, Interface=0x2244B4B8, Call Id=544
001161: Jun 18 10:52:17.265: //544/779D625585A7/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=38, Retry Count=0)
001162: Jun 18 10:52:17.265: //543/779D625585A7/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
001163: Jun 18 10:52:17.265: //544/779D625585A7/CCAPI/ccCallSetAAA_Accounting:
Accounting=1, Call Id=544
001164: Jun 18 10:52:17.265: //544/779D625585A7/CCAPI/ccCallDisconnect:
Cause Value=38, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=38)
001165: Jun 18 10:52:17.265: //544/779D625585A7/CCAPI/ccCallDisconnect:
Cause Value=38, Call Entry(Responsed=TRUE, Cause Value=38)
001166: Jun 18 10:52:17.269: //544/779D625585A7/CCAPI/cc_api_call_disconnect_done:
Disposition=-11, Interface=0x2244B4B8, Tag=0x0, Call Id=544,
Call Entry(Disconnect Cause=38, Voice Class Cause Code=0, Retry Count=0)
001167: Jun 18 10:52:17.269: //544/779D625585A7/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
001168: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001169: Jun 18 10:52:17.269: :cc_free_feature_vsa freeing 3C7BACB0
001170: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001171: Jun 18 10:52:17.269: vsacount in free is 3
001172: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001173: Jun 18 10:52:17.269: vsacount in free is 2
001174: Jun 18 10:52:17.269: //544/779D625585A7/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x0x235C27F8
State of The Call : STATE_DEAD
TCP Sockets Used : YES
Calling Number : 8123578219
Called Number : 1990
Source IP Address (Sig ): 192.168.85.253
Destn SIP Req Addr:Port : 192.168.85.246:5060
Destn SIP Resp Addr:Port : 192.168.85.246:5060
Destination Name : 192.168.85.246

001175: Jun 18 10:52:17.269: //544/779D625585A7/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): 192.168.85.253
Source IP Port (Media): 17376
Destn IP Address (Media): -
Destn IP Port (Media): 0
Orig Destn IP Address:Port (Media): [ - ]:0

001176: Jun 18 10:52:17.269: //544/779D625585A7/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 38
Disconnect Cause (SIP) : 503

001177: Jun 18 10:52:17.269: //543/779D625585A7/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=1, Params=0x23EC0C08, Progress Indication=NULL(0)
001178: Jun 18 10:52:17.269: //543/779D625585A7/CCAPI/ccCheckClipClir:
In: Calling Number=8123578219(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
001179: Jun 18 10:52:17.269: //543/779D625585A7/CCAPI/ccCheckClipClir:
Out: Calling Number=8123578219(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
001180: Jun 18 10:52:17.269: //543/779D625585A7/CCAPI/ccCallSetupRequest:
Destination Pattern=1..., Called Number=1990, Digit Strip=FALSE
001181: Jun 18 10:52:17.269: //543/779D625585A7/CCAPI/ccCallSetupRequest:
Calling Number=8123578219(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=1990(TON=Unknown, NPI=Unknown),
Redirect Number=8182203550, Display Info=
Account Number=8123578219, Final Destination Flag=TRUE,
Guid=779D6255-34A3-11E6-85A7-93B5B9697DD2, Outgoing Dial-peer=1
001182: Jun 18 10:52:17.269: //543/779D625585A7/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=8123578219
----- ccCallInfo IE subfields -----
cisco-ani=8123578219
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=1990
cisco-desttype=0
cisco-destplan=0
cisco-rdie=74
cisco-rdn=8182203550
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=4 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

001183: Jun 18 10:52:17.269: //543/779D625585A7/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x2244B4B8, Interface Type=3, Destination=, Mode=0x0,
Call Params(Calling Number=8123578219,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=1990(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=1, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
001184: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001185: Jun 18 10:52:17.269: :cc_get_feature_vsa malloc success
001186: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001187: Jun 18 10:52:17.269: cc_get_feature_vsa count is 3
001188: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001189: Jun 18 10:52:17.269: :FEATURE_VSA attributes are: feature_name:0,feature_time:1014738552,feature_id:1002
001190: Jun 18 10:52:17.269: //543/779D625585A7/CCAPI/cc_api_event_indication:
Event=101, Call Id=543
001191: Jun 18 10:52:17.269: //543/779D625585A7/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
001192: Jun 18 10:52:17.269: //545/779D625585A7/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
001193: Jun 18 10:52:17.269: //545/779D625585A7/CCAPI/ccCallSetContext:
Context=0x23EC0BB8
001194: Jun 18 10:52:17.269: //543/779D625585A7/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=1
001195: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001196: Jun 18 10:52:17.269: feature call forward featname is 13
001197: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001198: Jun 18 10:52:17.269: feature_vsa is 3C7BAE70,featname is 0
001199: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001200: Jun 18 10:52:17.269: :cc_get_feature_vsa malloc success
001201: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001202: Jun 18 10:52:17.269: cc_get_feature_vsa count is 4
001203: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001204: Jun 18 10:52:17.269: :FEATURE_VSA attributes are: feature_name:0,feature_time:1014738104,feature_id:1003
001205: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001206: Jun 18 10:52:17.269: : Generating new feature vsa,inserting it
001207: Jun 18 10:52:17.269: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001208: Jun 18 10:52:17.269: feature call forward featname is 13
001209: Jun 18 10:52:17.273: //545/779D625585A7/CCAPI/ccGetMediaClassTag:
media class tag 0
001210: Jun 18 10:52:17.273: //545/779D625585A7/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
001211: Jun 18 10:52:17.273: //543/779D625585A7/CCAPI/ccGetMediaClassTag:
media class tag 0
001212: Jun 18 10:52:17.273: //543/779D625585A7/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
001213: Jun 18 10:52:17.273: //545/779D625585A7/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
001214: Jun 18 10:52:17.273: //543/779D625585A7/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
001215: Jun 18 10:52:17.273: //545/779D625585A7/CCAPI/cc_api_event_indication:
Event=193, Call Id=545
001216: Jun 18 10:52:17.273: //545/779D625585A7/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
001217: Jun 18 10:52:17.273: //543/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001218: Jun 18 10:52:17.273: cc_api_get_xcode_stream : 4982
001219: Jun 18 10:52:17.273: //545/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001220: Jun 18 10:52:17.273: cc_api_get_xcode_stream : 4982
001221: Jun 18 10:52:17.273: //543/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001222: Jun 18 10:52:17.273: cc_api_get_xcode_stream : 4982
001223: Jun 18 10:52:17.273: //543/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001224: Jun 18 10:52:17.273: cc_api_get_xcode_stream : 4982
001225: Jun 18 10:52:17.273: //545/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001226: Jun 18 10:52:17.273: cc_api_get_xcode_stream : 4982
001227: Jun 18 10:52:17.273: //543/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001228: Jun 18 10:52:17.273: cc_api_get_xcode_stream : 4982
001229: Jun 18 10:52:17.273: //545/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001230: Jun 18 10:52:17.273: cc_api_get_xcode_stream : 4982
001231: Jun 18 10:52:17.273: //545/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001232: Jun 18 10:52:17.273: cc_api_get_xcode_stream : 4982
001233: Jun 18 10:52:17.273: //545/779D625585A7/CCAPI/cc_api_call_proceeding:
Interface=0x2244B4B8, Progress Indication=NULL(0)
001234: Jun 18 10:52:17.277: //545/779D625585A7/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:1990@10.100.11.250:5060 SIP/2.0
Via: SIP/2.0/TCP 172.17.2.1:5060;branch=z9hG4bK1791A2
Remote-Party-ID: <sip:8123578219@172.17.2.1>;party=calling;screen=no;privacy=off
From: <sip:8123578219@172.17.2.1>;tag=265ED3C-19B8
To: <sip:1990@10.100.11.250>
Date: Sat, 18 Jun 2016 15:52:17 GMT

Call-ID: 77A10BDE-34A311E6-85AF93B5-B9697DD2@172.17.2.1
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 2006803029-0883102182-2242352053-3110698450
User-Agent: Cisco-SIPGateway/IOS-15.4.1.T3
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1466265137
Contact: <sip:8123578219@172.17.2.1:5060;transport=tcp>
Call-Info: <sip:172.17.2.1:5060>;method="NOTIFY;Event=telephone-event;Duration=2000"
Expires: 180
Allow-Events: telephone-event
Max-Forwards: 65
Diversion: <sip:8182203550@200.57.0.85>;reason=deflection;counter=1
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 357

v=0
o=CiscoSystemsSIP-GW-UserAgent 7189 839 IN IP4 172.17.2.1
s=SIP Call
c=IN IP4 172.17.2.1
t=0 0
m=audio 17378 RTP/AVP 8 0 18 100 97
c=IN IP4 172.17.2.1
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
a=ptime:20

001235: Jun 18 10:52:17.429: //545/779D625585A7/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 172.17.2.1:5060;branch=z9hG4bK1791A2
From: <sip:8123578219@172.17.2.1>;tag=265ED3C-19B8
To: <sip:1990@10.100.11.250>
Date: Sat, 18 Jun 2016 15:52:17 GMT

Call-ID: 77A10BDE-34A311E6-85AF93B5-B9697DD2@172.17.2.1
CSeq: 101 INVITE
Allow-Events: presence
Content-Length: 0

001236: Jun 18 10:52:17.597: //545/779D625585A7/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 403 Forbidden
Via: SIP/2.0/TCP 172.17.2.1:5060;branch=z9hG4bK1791A2
From: <sip:8123578219@172.17.2.1>;tag=265ED3C-19B8
To: <sip:1990@10.100.11.250>;tag=5354348~fbcc660c-728d-4870-b705-cd2e96712d98-19268618
Date: Sat, 18 Jun 2016 15:52:17 GMT
Call-ID: 77A10BDE-34A311E6-85AF93B5-B9697DD2@172.17.2.1
CSeq: 101 INVITE
Allow-Events: presence
Reason: Q.850;cause=21
Content-Length: 0

001237: Jun 18 10:52:17.597: //545/779D625585A7/CCAPI/cc_api_call_disconnected:
Cause Value=57, Interface=0x2244B4B8, Call Id=545
001238: Jun 18 10:52:17.597: //545/779D625585A7/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=57, Retry Count=0)
001239: Jun 18 10:52:17.597: //543/779D625585A7/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
001240: Jun 18 10:52:17.597: //545/779D625585A7/CCAPI/ccCallSetAAA_Accounting:
Accounting=0, Call Id=545
001241: Jun 18 10:52:17.597: //545/779D625585A7/CCAPI/ccCallDisconnect:
Cause Value=57, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=57)
001242: Jun 18 10:52:17.597: //545/779D625585A7/CCAPI/ccCallDisconnect:
Cause Value=57, Call Entry(Responsed=TRUE, Cause Value=57)
001243: Jun 18 10:52:17.597: //545/779D625585A7/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x2244B4B8, Tag=0x0, Call Id=545,
Call Entry(Disconnect Cause=57, Voice Class Cause Code=0, Retry Count=0)
001244: Jun 18 10:52:17.597: //545/779D625585A7/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
001245: Jun 18 10:52:17.597: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001246: Jun 18 10:52:17.597: :cc_free_feature_vsa freeing 3C7BACB0
001247: Jun 18 10:52:17.597: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001248: Jun 18 10:52:17.597: vsacount in free is 3
001249: Jun 18 10:52:17.597: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001250: Jun 18 10:52:17.597: vsacount in free is 2
001251: Jun 18 10:52:17.597: //545/779D625585A7/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x0x235C27F8
State of The Call : STATE_DEAD
TCP Sockets Used : YES
Calling Number : 8123578219
Called Number : 1990
Source IP Address (Sig ): 172.17.2.1
Destn SIP Req Addr:Port : 10.100.11.250:5060
Destn SIP Resp Addr:Port : 10.100.11.250:5060
Destination Name : 10.100.11.250

001252: Jun 18 10:52:17.597: //545/779D625585A7/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): 172.17.2.1
Source IP Port (Media): 17378
Destn IP Address (Media): -
Destn IP Port (Media): 0
Orig Destn IP Address:Port (Media): [ - ]:0

001253: Jun 18 10:52:17.601: //545/779D625585A7/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 57
Disconnect Cause (SIP) : 403

Version gateway:  Version 15.0(1r)M16

CUCM version: 8.6

I think I have a problem between CUCM and gateway. When I do csim start internal_ext, the IP phone doesn't ring.

[HeribertoVV]

This is my gateway config...

default-router 192.168.86.250
option 150 ip 10.100.11.250

no ip domain lookup
ip multicast-routing
ip cef
no ipv6 cef
!
multilink bundle-name authenticated
!
!
!
!
!
!
!
voice-card 0
dspfarm
dsp services dspfarm
!
!
!
voice service voip
ip address trusted list
ipv4 192.168.186.15
ipv4 10.100.11.250
ipv4 10.100.11.253
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
h323
modem passthrough nse codec g711alaw
sip
session transport tcp
registrar server
g729 annexb-all
!
voice class codec 1
codec preference 1 g711ulaw
codec preference 2 g711alaw
codec preference 3 g729r8
!
voice class codec 2
codec preference 1 g711alaw
!
voice class codec 3
codec preference 1 g722-64
codec preference 2 g711ulaw
codec preference 3 g711alaw
codec preference 4 g729r8
!
!
voice iec syslog
!
!
voice translation-rule 3
rule 1 /8182203550/ /1990/
rule 2 /8121278749/ /1109/
rule 3 /8182203555/ /1990/
rule 4 /8182203556/ /1990/
rule 5 /8182203557/ /1990/
rule 6 /8182203558/ /1990/
rule 7 /8182203563/ /1990/
rule 8 /8182203564/ /1990/
rule 9 /8182203565/ /1990/
rule 10 /8182203567/ /1990/
rule 11 /8182203568/ /1990/
rule 12 /8182203573/ /1990/
rule 13 /8183743550/ /1990/
!
voice translation-rule 5
rule 1 reject /5549999980/
!
voice translation-rule 110
rule 1 /^9/ //
!
!
voice translation-profile BLOQUEO
translate calling 5
!
voice translation-profile SIP-IN
translate called 3
!
voice translation-profile TELUM
translate calling 3
translate called 110
!
!
!
license udi pid CISCO2911/K9 sn FJC1927A2A5
license accept end user agreement
hw-module ism 0
!
hw-module pvdm 0/0
!
!
!
username jduran privilege 15 secret 5 $1$55ak$OWvQ316ZZAjPMN5QQkKa31
username pavel privilege 15 secret 5 $1$FczG$FbLUBvlsxv6Rb3oR4NOc70
username rtrevinoa privilege 15 secret 5 $1$impE$CgYSxvGk6UhxACtUaqVd/.
username lolivo privilege 15 secret 5 $1$qvCA$gN5LWaYZ80rSO0mkamnfq.
username sergio privilege 15 secret 5 $1$1QN8$.3YgfPd1OJq/rS/6Lm.520
username hvelazquez privilege 15 secret 5 $1$RhBF$bbfOAKUX9jsGMdO9GNc9j1
!
redundancy
!
!
!
!
!
ip ssh source-interface GigabitEthernet0/1
!
!
!
!
!
!
!
!
!
!
interface Loopback0
ip address 172.17.2.1 255.255.255.255
ip pim sparse-mode
!
interface Embedded-Service-Engine0/0
no ip address
shutdown
!
interface GigabitEthernet0/0
ip address 192.168.186.130 255.255.255.0
ip pim sparse-mode
duplex full
speed 100
!
interface ISM0/0
ip unnumbered GigabitEthernet0/1.2
service-module ip address 192.168.85.246 255.255.255.0
!Application: CUE Running on ISM
service-module ip default-gateway 192.168.85.253
!
interface GigabitEthernet0/1
no ip address
duplex auto
speed auto
!
interface GigabitEthernet0/1.2
description Conexion con TLSA_MTY-MKI_MX80-01
encapsulation dot1Q 2 native
ip address 192.168.85.253 255.255.255.0
ip pim sparse-mode
!
interface GigabitEthernet0/1.3
description Telefonia
encapsulation dot1Q 3
ip address 192.168.86.250 255.255.255.0
ip pim sparse-mode
!
interface GigabitEthernet0/2
no ip address
shutdown
duplex auto
speed auto
!
interface ISM0/1
description Internal switch interface connected to Internal Service Module
no ip address
!
interface Vlan1
no ip address
shutdown
!
ip forward-protocol nd
!
no ip http server
no ip http secure-server
!
ip pim rp-address 10.100.11.250
ip route 0.0.0.0 0.0.0.0 192.168.85.254
ip route 192.168.85.246 255.255.255.255 ISM0/0
!
!
!
!
control-plane
!
!
!
!
!
!
mgcp behavior rsip-range tgcp-only
mgcp behavior comedia-role none
mgcp behavior comedia-check-media-src disable
mgcp behavior comedia-sdp-force disable
!
mgcp profile default
!
sccp local GigabitEthernet0/1.2
sccp ccm 10.100.11.250 identifier 1 version 7.0
sccp
!
sccp ccm group 1
associate ccm 1 priority 1
associate profile 3 register mtp-thelsa
associate profile 2 register xcode_Thelsa
associate profile 1 register Thelsaconf
!
ccm-manager music-on-hold
!
!
dspfarm profile 2 transcode
codec g729br8
codec g729r8
codec g711ulaw
codec g711alaw
codec g729ar8
codec g729abr8
maximum sessions 5
associate application SCCP
!
dspfarm profile 1 conference
codec g711ulaw
codec g711alaw
codec g729ar8
codec g729abr8
codec g729r8
codec g729br8
maximum sessions 4
associate application SCCP
!
dspfarm profile 3 mtp
codec g711ulaw
maximum sessions hardware 12
associate application SCCP
!
dial-peer voice 101 voip
description ** Llamada a troncal SIP2 **
destination-pattern 044..........
session protocol sipv2
session target ipv4:192.168.186.15
voice-class codec 1
voice-class sip early-offer forced
voice-class sip bind control source-interface GigabitEthernet0/0
voice-class sip bind media source-interface GigabitEthernet0/0
dtmf-relay rtp-nte
clid network-number 8182203550
no vad
!
dial-peer voice 1 voip
description DIAL TO CUCM
destination-pattern 1...
rtp payload-type cisco-codec-fax-ack 105
rtp payload-type cisco-codec-fax-ind 106
rtp payload-type nte 97
session protocol sipv2
session target ipv4:10.100.11.250
voice-class codec 1
voice-class sip bind control source-interface Loopback0
voice-class sip bind media source-interface Loopback0
dtmf-relay rtp-nte sip-notify
no vad
!
dial-peer voice 201 voip
description ** Llamadas entrantes de troncal de SIP ***
translation-profile incoming SIP-IN
call-block translation-profile incoming BLOQUEO
call-block disconnect-cause incoming unassigned-number
rtp payload-type cisco-codec-fax-ack 105
rtp payload-type cisco-codec-fax-ind 106
rtp payload-type nte 97
session protocol sipv2
incoming called-number .
voice-class codec 1
dtmf-relay rtp-nte
no vad
!
dial-peer voice 102 voip
description ** Llamada a troncal SIP-CELNAL **
destination-pattern 045..........
session protocol sipv2
session target ipv4:192.168.186.15
voice-class codec 1
voice-class sip early-offer forced
voice-class sip bind control source-interface GigabitEthernet0/0
voice-class sip bind media source-interface GigabitEthernet0/0
dtmf-relay rtp-nte
clid network-number 8182203550
no vad
!
dial-peer voice 103 voip
description ** Llamada a troncal SIP-LOCAL **
destination-pattern [1-2,8].......
session protocol sipv2
session target ipv4:192.168.186.15
voice-class codec 1
voice-class sip early-offer forced
voice-class sip bind control source-interface GigabitEthernet0/0
voice-class sip bind media source-interface GigabitEthernet0/0
dtmf-relay rtp-nte
clid network-number 8182203550
no vad
!
dial-peer voice 104 voip
description ** Llamada a troncal SIP-NACIONAL **
destination-pattern 01..........
session protocol sipv2
session target ipv4:192.168.186.15
voice-class codec 1
voice-class sip early-offer forced
voice-class sip bind control source-interface GigabitEthernet0/0
voice-class sip bind media source-interface GigabitEthernet0/0
dtmf-relay rtp-nte
clid network-number 8182203550
no vad
!
dial-peer voice 2 voip
description DIAL TO CUCM
destination-pattern 2...
rtp payload-type cisco-codec-fax-ack 105
rtp payload-type cisco-codec-fax-ind 106
rtp payload-type nte 97
session protocol sipv2
session target ipv4:10.100.11.250
voice-class codec 1
voice-class sip bind media source-interface Loopback0
dtmf-relay rtp-nte sip-notify
no vad
!
dial-peer voice 105 voip
description ** Llamada a troncal SIP-Internacional **
destination-pattern 001T
session protocol sipv2
session target ipv4:192.168.186.15
voice-class codec 1
voice-class sip early-offer forced
voice-class sip bind control source-interface GigabitEthernet0/0
voice-class sip bind media source-interface GigabitEthernet0/0
dtmf-relay rtp-nte
clid network-number 8182203550
no vad
!
dial-peer voice 106 voip
description **Contact Center**
destination-pattern 7...
rtp payload-type cisco-codec-fax-ack 105
rtp payload-type cisco-codec-fax-ind 106
rtp payload-type nte 97
session protocol sipv2
session target ipv4:10.100.11.250
voice-class codec 1
voice-class sip bind control source-interface Loopback0
voice-class sip bind media source-interface Loopback0
dtmf-relay rtp-nte sip-notify
no vad
!
dial-peer voice 107 voip
description ** Llamada a troncal SIP-Internacional **
destination-pattern 005T
session protocol sipv2
session target ipv4:192.168.186.15
voice-class codec 1
voice-class sip early-offer forced
voice-class sip bind media source-interface GigabitEthernet0/0
dtmf-relay rtp-nte
clid network-number 8182203550
!
dial-peer voice 1999 voip
description **voicemail**
destination-pattern 1990
session protocol sipv2
session target ipv4:192.168.85.246
voice-class codec 1
dtmf-relay sip-notify
no vad
!
dial-peer voice 108 voip
description ** Llamada a troncal SIP-Internacional **
shutdown
destination-pattern 0456181110751
session protocol sipv2
session target ipv4:192.168.186.15
voice-class codec 2
voice-class sip early-offer forced
voice-class sip bind media source-interface GigabitEthernet0/0
dtmf-relay rtp-nte
clid network-number 8182203550
!
dial-peer voice 109 voip
description ** Llamada a troncal SIP-Internacional **
destination-pattern 003T
session protocol sipv2
session target ipv4:192.168.186.15
voice-class codec 1
voice-class sip early-offer forced
voice-class sip bind media source-interface GigabitEthernet0/0
dtmf-relay rtp-nte
clid network-number 8182203550
no vad
!
dial-peer voice 1400 voip
description CUE_VENTAS
destination-pattern 1500
session protocol sipv2
session target ipv4:192.168.85.246
voice-class codec 1
dtmf-relay sip-notify
no vad
!
dial-peer voice 1970 voip
description **VM-THELSA**
destination-pattern 1970
session protocol sipv2
session target ipv4:192.168.85.246
voice-class codec 1
dtmf-relay sip-notify
no vad
!
dial-peer voice 1880 voip
description **Contact Center-VENTAS**
destination-pattern 18..
rtp payload-type cisco-codec-fax-ack 105
rtp payload-type cisco-codec-fax-ind 106
rtp payload-type nte 97
session protocol sipv2
session target ipv4:10.100.11.250
voice-class codec 1
voice-class sip bind media source-interface Loopback0
dtmf-relay rtp-nte sip-notify
no vad
!
!
dial-peer inbound selection sip-trunk
!
!
gatekeeper
shutdown
!

[Suresh Hudda]

Have you assigned MRGL with transcoder to sip trunk or phone's device pool ?

Suresh

[HeribertoVV]

Hello Suresh,

I have Transcoder configured for this Trunk´s MRGL. I made a test by changing my transcoder using another one and I still have the same result. Incoming calls fail.

Any another feedback?

[Suresh Hudda]

Okay 2 things I would see one by one before collecting cucm logs.

1. Put the phone and sip trunk in same region which uses g729 codec.

2. Remove the partition from 1990 ext and remove CSS from sip trunk.

Suresh

[HeribertoVV]

Hi Deepak and Suresh.

I attach here callManager and gateway logs. I made two new calls and captured logs. I did what Suresh recommended before taking the logs. Calls where made at 13:15.

Thanks for all your help.

[Deepak Mehta · Accepted solution]

Thanks ,

What i have found in the logs is that SIP trunk is trying to forward the request to a device name "CUETHELSA" with 192.168.85.246 ip. I am wondering why call is getting forwarded to CUE voicemail , do you have any forwarding on or same type DN set which might be kicking in here.please do a check again.thanks

"StationCtiCdpc-CtiEnableReq CH=0|0 DevName=cuethelsa DN=1990 .

++++++++++++++++++++++++++++++++++++++++++++++++++

then later we see call reject message from the device cuethelsa(192.168.85.246).

13:15:18.391 |StationCdpc(170): StationCtiCdpc-StationLineCallReject CH=1|26632470 CmSsFeat=1 RFR=0 OnBehalf=Device Reason=1 State=1|1,200,21,1.6014471^192.168.85.246^cuethelsa

+++++++++++++++++++++++++++++++++++++++++

Then we get release call with unable to find the device message.

13:15:18.391 |StationD(86): StationCtiD-StationOutputSetRinger|1,200,21,1.6014471^192.168.85.246^cuethelsa
13:15:18.391 |deleteCi: Unable to find the device that owns the call with CI=26632470|1,200,21,1.6014471^192.168.85.246^cuethelsa
13:15:18.391 |LineControl(129) - Release call instance=1 for CI=26632470|1,200,21,1.6014471^192.168.85.246^cuethelsa

[HeribertoVV]

I have 1990 DN defined as a DN for the route point to CUE. In CUE I have defined 1990 as the incoming number for IVR. I changed the number to 1995 and when I clicked "save" I watched that "Warning:  Number of sessions exceeds license limit.  Do you want to continue?". It seems to be the problem. As a workaround I changed my dial-rules in gateway in order to make calls go directly to my UCCX. It worked.

Attached is the licenses page in CUE. What kind of licenses do I need more?

[Deepak Mehta]

Thanks for update , good to know that we were able to finally get to reason for the failures.

For licensing issue you can follow below links and try to activate the evaluation licenses on your CUE .I can see it is not activated right now.

https://adamccie.wordpress.com/2015/09/16/cue-licensing-faq/

https://supportforums.cisco.com/document/32076/cue-licensing-faq#How_to_resolve_the_error_Session_value_exceeds_license_limit

Good luck!

Warm Regards,

Deepak Mehta

[Deepak Mehta]

Also please make sure you have correct IP address given on  CUCM SIP trunk -172.17.2.1 and SIP trunk has access to internal partition to reach 1990 extension.

Thanks

[HeribertoVV]

Hi Deepak,

I rechecked my configuration and I saw I already had that IP address for my SIP Trunk and it has access to my internal partition to reach 1990. What gets my attention is that when I use csim to any internal number, the IP phone never rings.

Another thing: when I call to the number given by our ITSP the message I hear is from our ITSP and not from CUCM o gateway (busy tone). Instead I receive "We are sorry, but the call cannot be stablished right now. Try calling later". This is a normal behavior?

Kind regards,

Heriberto.

[Deepak Mehta]

We can see GW is sending invite with all preferred codecs and bye is coming from CUCM side so it would be appropriate to collect Detailed CCM logs next as suggested by Suresh as well.As far as CSIM start ,i am not sure as it is even supported with SIP trunk.Thanks