unable to make outbound calls. incoming works

jomar050485 · ‎07-19-2011

here is what i am getting

2821#csim start 919566558888

csim: called number = 9195665588888, loop count = 1 ping count = 0

002550: *Jul 20 06:06:13.885: ISDN Se0/0/0:23 Q931: Sending SETUP callref = 0x0092 callID = 0x8013 switch = primary-dms100 interface = User

002551: *Jul 20 06:06:13.889: ISDN Se0/0/0:23 Q931: TX -> SETUP pd = 8 callref = 0x0092

Bearer Capability i = 0x8090A2

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0xA98397

Exclusive, Channel 23

Called Party Number i = 0x80, '19566558888'

Plan:Unknown, Type:Unknown

002552: *Jul 20 06:06:13.921: ISDN Se0/0/0:23 Q931: RX <- CALL_PROC pd = 8 callref = 0x8092

Channel ID i = 0xA98397

Exclusive, Channel 23

002553: *Jul 20 06:06:15.053: ISDN Se0/0/0:23 Q931: RX <- PROGRESS pd = 8 callref = 0x8092

Progress Ind i = 0x8A81 - Call not end-to-end ISDN, may have in-band info

csim: loop = 1, failed = 1

csim: call attempted = 1, setup failed = 1, tone failed = 0

GCHC_CENTRAL_2821#

002554: *Jul 20 06:06:35.073: ISDN Se0/0/0:23 Q931: TX -> DISCONNECT pd = 8 callref = 0x0092

Cause i = 0x8090 - Normal call clearing

002555: *Jul 20 06:06:35.109: ISDN Se0/0/0:23 Q931: RX <- RELEASE pd = 8 callref = 0x8092

002556: *Jul 20 06:06:35.117: ISDN Se0/0/0:23 Q931: TX -> RELEASE_COMP pd = 8 callref = 0x0092

controller T1 0/0/0

pri-group timeslots 1-24

interface Serial0/0/0:23

no ip address

encapsulation hdlc

isdn switch-type primary-dms100

isdn incoming-voice voice

no cdp enable

!

dial-peer voice 111 pots

destination-pattern 91[2-9]..[2-9]......

port 0/0/0:23

forward-digits 11

pmvillarama · ‎07-19-2011

Hi,

In the debug, the router disconnects the call. to isolate call inofrmation requirement, please do another test call with an actual phone. Also, please use "debug voice ccapi inout" and post the results.

jomar050485 · ‎07-19-2011

i can't test with an actual phone right now but i will tomorrow. if this helps any, i got a debug using csim again:

GCHC_CENTRAL_2821#csim start 919566558888

002557: *Jul 20 06:16:48.313: csim: called number = 919566558888, loop count = 1 ping count = 0

002558: *Jul 20 06:16:48.313: csimSetupPeer peer type(1), destPat(919566558888), matched(4), target()

002559: *Jul 20 06:16:48.313: //-1/xxxxxxxxxxxx/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=FALSE, Mode=0,

Outgoing Dial-peer=111, Params=0x4BC17AD4, Progress Indication=NULL(0)

002560: *Jul 20 06:16:48.313: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:

In: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

002561: *Jul 20 06:16:48.313: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:

Out: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

002562: *Jul 20 06:16:48.313: //-1/xxxxxxxxxxxx/CCAPI/ccCallSetupRequest:

Destination Pattern=91[2-9]..[2-9]......, Called Number=919566558888, Digit Strip=TRUE

002563: *Jul 20 06:16:48.313: //-1/xxxxxxxxxxxx/CCAPI/ccCallSetupRequest:

Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=919566558888(TON=Unknown, NPI=Unknown),

Redirect Number=, Display Info=

Account Number=, Final Destination Flag=FALSE,

Guid=AF46A1E8-B1CE-11E0-8FDA-BD6D004DD1DB, Outgoing Dial-peer=111

002564: *Jul 20 06:16:48.313: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=919566558888

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

002565: *Jul 20 06:16:48.317: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x4E7C5B3C, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=91956655888(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=111, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

002566: *Jul 20 06:16:48.317: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

002567: *Jul 20 06:16:48.317: :cc_get_feature_vsa malloc success

002568: *Jul 20 06:16:48.317: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

002569: *Jul 20 06:16:48.317: cc_get_feature_vsa count is 1

002570: *Jul 20 06:16:48.317: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

002571: *Jul 20 06:16:48.317: :FEATURE_VSA attributes are: feature_name:0,feature_time:1320377576,feature_id:331691

002572: *Jul 20 06:16:48.317: //331694/AF46A1E88FDA/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

002573: *Jul 20 06:16:48.317: //331694/AF46A1E88FDA/CCAPI/ccCallSetContext:

Context=0x4E7C290C

002574: *Jul 20 06:16:48.397: //331694/AF46A1E88FDA/CCAPI/cc_api_call_proceeding:

Interface=0x4E7C5B3C, Progress Indication=NULL(0)

002575: *Jul 20 06:16:48.401: csim_do_test: cid(331694), ev(23), disp(0)

002576: *Jul 20 06:16:48.401: csimTraceSct: cid(331694),st(0),oldst(0)

002577: *Jul 20 06:16:48.401: csimIgnore cid(331694), st(0),oldst(0), ev(23)

002578: *Jul 20 06:16:49.513: //331694/AF46A1E88FDA/CCAPI/cc_api_call_cut_progress:

Interface=0x4E7C5B3C, Progress Indication=NOT END TO END ISDN(1), Signal Indication=INTERCEPT(2),

Cause Value=0

002579: *Jul 20 06:16:49.513: //331694/AF46A1E88FDA/CCAPI/cc_api_call_cut_progress:

Call Entry(Responsed=TRUE)

002580: *Jul 20 06:16:49.517: csim_do_test: cid(331694), ev(24), disp(0)

002581: *Jul 20 06:16:49.517: csimTraceSct: cid(331694),st(0),oldst(0)

002582: *Jul 20 06:16:49.517: csimIgnore cid(331694), st(0),oldst(0), ev(24)

002583: *Jul 20 06:17:09.517: csim_do_test: timeout cid(331694), st(0), oldst(0)

002584: *Jul 20 06:17:09.517: //331694/AF46A1E88FDA/CCAPI/ccCallDisconnect:

Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

002585: *Jul 20 06:17:09.517: //331694/AF46A1E88FDA/CCAPI/ccCallDisconnect:

Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0)

002586: *Jul 20 06:17:09.517: //331694/AF46A1E88FDA/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

002587: *Jul 20 06:17:09.561: //331694/AF46A1E88FDA/CCAPI/cc_api_call_disconnect_done:

GCHC_CENTRAL_2821#Disposition=0, Interface=0x4E7C5B3C, Tag=0x0, Call Id=331694,

Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0)

002588: *Jul 20 06:17:09.561: //331694/AF46A1E88FDA/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

002589: *Jul 20 06:17:09.561: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

002590: *Jul 20 06:17:09.561: :cc_free_feature_vsa freeing 4EB35CE0

002591: *Jul 20 06:17:09.561: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

002592: *Jul 20 06:17:09.561: vsacount in free is 0

002593: *Jul 20 06:17:09.565: csim_do_test: cid(331694), ev(13), disp(0)

002594: *Jul 20 06:17:09.565: csimTraceSct: cid(331694),st(2),oldst(0)

002595: *Jul 20 06:17:10.065: csim: loop = 1, failed = 1

002596: *Jul 20 06:17:10.065: csim: call attempted = 1, setup failed = 1, tone failed = 0

002597: *Jul 20 06:17:10.065: //-1/xxxxxxxxxxxx/CCAPI/ccAppShutdownMode:

ccAppShutdownMode: remove it from the queue

GCHC_CENTRAL_2821#

pmvillarama · ‎07-19-2011

Hi,

thanks for the info; could you add the following command inside interface Serial0/0/0:23 and make another csim call with debug voice ccapi inout and debug isdn q931.

isdn send-alerting

isdn sending-complete

regards,

jomar050485 · ‎07-19-2011

thanks paul for all the help. it looks like its the same thing. i'm trying to convert from mgcp to h323. the call looks almost the same when it goes through mgcp (when configured for it) but continues on and doesn't stop where this q931 debug stops. i know it has to be something simple that i'm not putting correctly.

GCHC_CENTRAL_2821#csim start 919566558888

csim: called number = 919566558888, loop count = 1 ping count = 0

000553: *Jul 20 07:35:53.775: ISDN Se0/0/0:23 Q931: Sending SETUP callref = 0x0084 callID = 0x8005 switch = primary-dms100 interface = User

000554: *Jul 20 07:35:53.775: ISDN Se0/0/0:23 Q931: TX -> SETUP pd = 8 callref = 0x0084

Sending Complete

Bearer Capability i = 0x8090A2

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0xA98397

Exclusive, Channel 23

Called Party Number i = 0x80, '19566558888'

Plan:Unknown, Type:Unknown

000555: *Jul 20 07:35:53.819: ISDN Se0/0/0:23 Q931: RX <- CALL_PROC pd = 8 callref = 0x8084

Channel ID i = 0xA98397

Exclusive, Channel 23

000556: *Jul 20 07:35:54.951: ISDN Se0/0/0:23 Q931: RX <- PROGRESS pd = 8 callref = 0x8084

Progress Ind i = 0x8A81 - Call not end-to-end ISDN, may have in-band info

csim: loop = 1, failed = 1

csim: call attempted = 1, setup failed = 1, tone failed = 0

GCHC_CENTRAL_2821#

000557: *Jul 20 07:36:14.959: ISDN Se0/0/0:23 Q931: TX -> DISCONNECT pd = 8 callref = 0x0084

Cause i = 0x8090 - Normal call clearing

000558: *Jul 20 07:36:14.987: ISDN Se0/0/0:23 Q931: RX <- RELEASE pd = 8 callref = 0x8084

000559: *Jul 20 07:36:14.991: ISDN Se0/0/0:23 Q931: TX -> RELEASE_COMP pd = 8 callref = 0x0084

GCHC_CENTRAL_2821#csim start 919566558888

000560: *Jul 20 07:37:18.647: csim: called number = 919566558888, loop count = 1 ping count = 0

000561: *Jul 20 07:37:18.647: csimSetupPeer peer type(1), destPat(919566558888), matched(4), target()

000562: *Jul 20 07:37:18.647: //-1/xxxxxxxxxxxx/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=FALSE, Mode=0,

Outgoing Dial-peer=111, Params=0x4B283710, Progress Indication=NULL(0)

000563: *Jul 20 07:37:18.647: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:

In: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

000564: *Jul 20 07:37:18.651: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:

Out: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

000565: *Jul 20 07:37:18.651: //-1/xxxxxxxxxxxx/CCAPI/ccCallSetupRequest:

Destination Pattern=91[2-9]..[2-9]......, Called Number=919566558888, Digit Strip=TRUE

000566: *Jul 20 07:37:18.651: //-1/xxxxxxxxxxxx/CCAPI/ccCallSetupRequest:

Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=919566558888(TON=Unknown, NPI=Unknown),

Redirect Number=, Display Info=

Account Number=, Final Destination Flag=FALSE,

Guid=EE61223F-B1D9-11E0-801F-D1A2EBF3B629, Outgoing Dial-peer=111

000567: *Jul 20 07:37:18.651: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=919566558888

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

000568: *Jul 20 07:37:18.651: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x4B678A40, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Alowed),

Called Number=919566558888(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=111, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

000569: *Jul 20 07:37:18.651: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000570: *Jul 20 07:37:18.651: :cc_get_feature_vsa malloc success

000571: *Jul 20 07:37:18.651: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000572: *Jul 20 07:37:18.651: cc_get_feature_vsa count is 1

000573: *Jul 20 07:37:18.651: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000574: *Jul 20 07:37:18.651: :FEATURE_VSA attributes are: feature_name:0,feature_time:1261076912,feature_id:20

000575: *Jul 20 07:37:18.651: //20/EE61223F801F/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

000576: *Jul 20 07:37:18.651: //20/EE61223F801F/CCAPI/ccCallSetContext:

Context=0x4B6780A0

000577: *Jul 20 07:37:18.727: //20/EE61223F801F/CCAPI/cc_api_call_proceeding:

Interface=0x4B678A40, Progress Indication=NULL(0)

000578: *Jul 20 07:37:18.727: csim_do_test: cid(20), ev(23), disp(0)

000579: *Jul 20 07:37:18.727: csimTraceSct: cid(20),st(0),oldst(0)

000580: *Jul 20 07:37:18.727: csimIgnore cid(20), st(0),oldst(0), ev(23)

000581: *Jul 20 07:37:19.871: //20/EE61223F801F/CCAPI/cc_api_call_cut_progress:

Interface=0x4B678A40, Progress Indication=NOT END TO END ISDN(1), Signal Indication=INTERCEPT(2),

Cause Value=0

000582: *Jul 20 07:37:19.871: //20/EE61223F801F/CCAPI/cc_api_call_cut_progress:

Call Entry(Responsed=TRUE)

000583: *Jul 20 07:37:19.871: csim_do_test: cid(20), ev(24), disp(0)

000584: *Jul 20 07:37:19.871: csimTraceSct: cid(20),st(0),oldst(0)

000585: *Jul 20 07:37:19.871: csimIgnore cid(20), st(0),oldst(0), ev(24)

GCHC_CENTRAL_2821#

000586: *Jul 20 07:37:39.871: csim_do_test: timeout cid(20), st(0), oldst(0)

000587: *Jul 20 07:37:39.871: //20/EE61223F801F/CCAPI/ccCallDisconnect:

Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

000588: *Jul 20 07:37:39.871: //20/EE61223F801F/CCAPI/ccCallDisconnect:

Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0)

000589: *Jul 20 07:37:39.871: //20/EE61223F801F/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

000590: *Jul 20 07:37:39.895: //20/EE61223F801F/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x4B678A40, Tag=0x0, Call Id=20,

Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0)

000591: *Jul 20 07:37:39.895: //20/EE61223F801F/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

000592: *Jul 20 07:37:39.895: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

000593: *Jul 20 07:37:39.895: :cc_free_feature_vsa freeing 4B2A81A8

000594: *Jul 20 07:37:39.895: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

000595: *Jul 20 07:37:39.895: vsacount in free is 0

000596: *Jul 20 07:37:39.895: csim_do_test: cid(20), ev(13), disp(0)

000597: *Jul 20 07:37:39.895: csimTraceSct: cid(20),st(2),oldst(0)

000598: *Jul 20 07:37:40.395: csim: loop = 1, failed = 1

000599: *Jul 20 07:37:40.395: csim: call attempted = 1, setup failed = 1, tone failed = 0

000600: *Jul 20 07:37:40.395: //-1/xxxxxxxxxxxx/CCAPI/ccAppShutdownMode:

ccAppShutdownMode: remove it from the queue

GCHC_CENTRAL_2821#

pmvillarama · ‎07-19-2011

Hi,

I believe you're config should work in a normal h323-pstn call. If this doesn't work out using an actual phone, I suggest you change the IOS and try a few test calls.

regards,

jomar050485 · ‎07-20-2011

I tried it today but it still didn't work. Any other ideas?

I feel like it has to do with the isdn type primary-dms100. That's the way it was configured in cucm and it was working. I converted another gateway either and that one worked fine but that one was primary-ni2.

Maybe there is an extra command somewhere I have to put in..

pmvillarama · ‎07-20-2011

Hi,

If i may clarify,

CUCM - mgcp - Router (isdn switchtype primary-dms100) - PSTN = not working?

CUCM - h323 - Router (isdn switch type primary-dms100) - PSTN = not working?

CUCM - h323 - Router ( isdn switchtype primary-ni2) - PSTN = working?

If it is working in CUCM - mgcp and uses primary dms100, you can use a different switch type in router h323 like the primary-ni2 since it works fine on that

jomar050485 · ‎07-20-2011

Sorry Paul, let me clarify.

This was configured in cucm as an mgcp gateway. I am converting it to h323. Calls using MGCP were working great but I haven't had much success with it working in h323.

Honestly, I haven't tried it from an actual phone. I've only used csim. I don't want it to be down tomorrow morning. I may just have to let people try it.

Chad Stachowicz · ‎07-20-2011

I can tell you right now using CSIM start as any sort of reliable test of whether its working or not is a bad idea. I have been burnt so many times by the lack of that command working in certain areas, I stopped using it all together.

Don't use csim start for troubleshooting!!!!

Chad

jomar050485 · ‎07-20-2011

Well, i'm using it because it should work as it always has worked. i'm using it to verify that the pri can handle outbound calls. i've used this test alot and it has been pretty reliable. I guess i'm going to assume its working and if it doesn't work tomorrow I'll reload the old config since I didn't delete it from cucm.

Chad Stachowicz · ‎07-20-2011

Is there a reason you can load a CIPC and test with a real phone? happy to help troubleshoot if its still not working... but csim start is an unreliable test im sorry to say.

Chad

pmvillarama · ‎07-20-2011

Hi, you can use an h323 phone instead. try ephony . Sometimes, csim start helps depends on the situation.

Chad Stachowicz · ‎07-20-2011

post your entire configuration and screenshot cucm please.

Chad

Chad Stachowicz · ‎07-20-2011

Also please do a

show isdn status on the router.

Chad