Solved: Switching equipment congestion

datacenter · ‎04-09-2013

Hello guys!

1st of all i want to say that i researched a lot about the error bellow and i still getting same problem even afeter apllying the recommended workarounds.

631178: Mar 18 12:38:48.910: ISDN BR0/1/0 CCd: ie_in_out_msg: message 0x84 ie = 25 ie_allowed =0

631179: Mar 18 12:38:48.910: ISDN BR0/1/0 Q931: TX -> DISCONNECT pd = 8 callref = 0x50

Cause i = 0x82AA - Switching equipment congestion

We have a branch that uses Siemens as a analogical pabx, while trying to connect from here we got this error in q931 debug.

The call gets dropped in the moment that the person answers the call... it seems like there is any kind of codec negotiation problem but i'm not sure.

Our CUCM runs in 8.5 version and i'm trying to get additional info from RTMT once it can be CUCM sending disconnection and not only the router right?

The problem is that we've upgraded our cucm recently and the VLT (voice log translator) is not recognizing the logs i get from RTMT and i cant understand the whole thing without it.

When i try to load calllogs it says that "No log messages in selected file(s)".

Tried other app's too but no way... im just openin RTMT, selecting Tools > Trace & Log Central > Collect files: Select Call Manager, next next... thats it! (the log file is attached)

Opening via Excel i get it this way:

2013/04/09 08:43:02.894|CC|SETUP|21585265|21585266|55476914|35122734|35122734

2013/04/09 08:43:03.716|CC|SETUP|21585267|21585268|55476914|734|734

2013/04/09 08:43:08.042|CC|REJECT|21585267|21585268|55476914|734|734|47

Well, this is not that clear but i think i made my point.

Thanks in advance!
Daniel

Ayodeji Okanlawon · ‎04-09-2013

Ok,

From these logs, We can see that the problem is not with the ISDN leg of the call. The call is actually been disconnected by CUCM and the reason is cause 47..This is usually a media capabilities issue..

What is the codec configured on Dial-peer 37 ? (from the log it looks like its G729)

What is the region between the gateway and the IP phone that made the call 55476914. If the codec on dial-peer 37 is G729 and the region is set to G711 then you will have this issue...

Can you send your sh run..

also do another test call and send

debug h225 asn1

debug h245 asn1

debug voip ccapi inout

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts

View solution in original post

Ayodeji Okanlawon · ‎04-09-2013

Looking at the debugs, your gateway is sending the disconnect..

Can you send the output of

'show voice dsp detail'

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts

datacenter · ‎04-09-2013

Thanks!

Bellow sh voice dsp detailed of our branch's router.

DSP DSP DSPWARE CURR BOOT PAK TX/RX

TYPE NUM CH CODEC VERSION STATE STATE RST AI VOICEPORT TS ABORT PACK COUNT

==== === == ======== ========== ===== ======= === == ========= == ===== ============

edsp 0001 01 g711ulaw 0.1 IDLE 50/0/1.1

edsp 0002 01 g729r8 p 0.1 IDLE 50/0/2.1

edsp 0003 01 g711ulaw 0.1 IDLE 50/0/3.1

edsp 0004 01 g729r8 p 0.1 IDLE 50/0/4.1

edsp 0005 01 g729r8 p 0.1 IDLE 50/0/5.1

edsp 0006 01 g729r8 p 0.1 IDLE 50/0/6.1

edsp 0007 01 g729r8 p 0.1 IDLE 50/0/7.1

edsp 0008 01 g729r8 p 0.1 IDLE 50/0/8.1

edsp 0009 01 g729r8 p 0.1 IDLE 50/0/9.1

edsp 0010 01 g729r8 p 0.1 IDLE 50/0/10.1

edsp 0011 01 g729r8 p 0.1 IDLE 50/0/11.1

edsp 0012 01 g729r8 p 0.1 IDLE 50/0/12.1

edsp 0013 01 g729r8 p 0.1 IDLE 50/0/13.1

edsp 0014 01 g729r8 p 0.1 IDLE 50/0/14.1

edsp 0015 01 g729r8 p 0.1 IDLE 50/0/15.1

edsp 0016 01 g729r8 p 0.1 IDLE 50/0/16.1

edsp 0017 01 g729r8 p 0.1 IDLE 50/0/17.1

edsp 0018 01 g711ulaw 0.1 IDLE 50/0/18.1

edsp 0019 01 g729r8 p 0.1 IDLE 50/0/19.1

edsp 0020 01 g729r8 p 0.1 IDLE 50/0/20.1

edsp 0021 01 g729r8 p 0.1 IDLE 50/0/21.1

edsp 0022 01 g729r8 p 0.1 IDLE 50/0/22.1

edsp 0023 01 g729r8 p 0.1 IDLE 50/0/23.1

edsp 0024 01 g729r8 p 0.1 IDLE 50/0/24.1

edsp 0025 01 g729r8 p 0.1 IDLE 50/0/25.1

edsp 0026 01 g729r8 p 0.1 IDLE 50/0/26.1

edsp 0027 01 g729r8 p 0.1 IDLE 50/0/27.1

edsp 0028 01 g729r8 p 0.1 IDLE 50/0/28.1

edsp 0029 01 g729r8 p 0.1 IDLE 50/0/29.1

edsp 0030 01 g729r8 p 0.1 IDLE 50/0/30.1

edsp 0031 01 g729r8 p 0.1 IDLE 50/0/31.1

edsp 0032 01 g729r8 p 0.1 IDLE 50/0/32.1

edsp 0033 01 g729r8 p 0.1 IDLE 50/0/33.1

edsp 0034 01 g729r8 p 0.1 IDLE 50/0/34.1

edsp 0035 01 g729r8 p 0.1 IDLE 50/0/35.1

edsp 0036 01 g729r8 p 0.1 IDLE 50/0/36.1

edsp 0037 01 g729r8 p 0.1 IDLE 50/0/37.1

edsp 0038 01 g729r8 p 0.1 IDLE 50/0/38.1

edsp 0039 01 g729r8 p 0.1 IDLE 50/0/39.1

edsp 0040 01 g729r8 p 0.1 IDLE 50/0/40.1

edsp 0041 01 g729r8 p 0.1 IDLE 50/0/41.1

edsp 0042 01 g729r8 p 0.1 IDLE 50/0/42.1

edsp 0043 01 g729r8 p 0.1 IDLE 50/0/43.1

edsp 0044 01 g729r8 p 0.1 IDLE 50/0/44.1

edsp 0045 01 g729r8 p 0.1 IDLE 50/0/45.1

edsp 0046 01 g729r8 p 0.1 IDLE 50/0/46.1

edsp 0047 01 g729r8 p 0.1 IDLE 50/0/47.1

edsp 0048 01 g729r8 p 0.1 IDLE 50/0/48.1

edsp 0049 01 g729r8 p 0.1 IDLE 50/0/49.1

edsp 0050 01 g729r8 p 0.1 IDLE 50/0/50.1

edsp 0051 01 g729r8 p 0.1 IDLE 50/0/51.1

edsp 0052 01 g729r8 p 0.1 IDLE 50/0/52.1

edsp 0053 01 g729r8 p 0.1 IDLE 50/0/53.1

edsp 0054 01 g729r8 p 0.1 IDLE 50/0/54.1

edsp 0055 01 g729r8 p 0.1 IDLE 50/0/55.1

edsp 0056 01 g729r8 p 0.1 IDLE 50/0/56.1

edsp 0057 01 g729r8 p 0.1 IDLE 50/0/57.1

edsp 0058 01 g729r8 p 0.1 IDLE 50/0/58.1

edsp 0059 01 g729r8 p 0.1 IDLE 50/0/59.1

edsp 0060 01 g729r8 p 0.1 IDLE 50/0/60.1

edsp 0061 01 g729r8 p 0.1 IDLE 50/0/61.1

edsp 0062 01 g729r8 p 0.1 IDLE 50/0/62.1

edsp 0063 01 g729r8 p 0.1 IDLE 50/0/63.1

edsp 0064 01 g729r8 p 0.1 IDLE 50/0/64.1

edsp 0065 01 g729r8 p 0.1 IDLE 50/0/65.1

edsp 0066 01 g729r8 p 0.1 IDLE 50/0/66.1

edsp 0067 01 g729r8 p 0.1 IDLE 50/0/67.1

edsp 0068 01 g729r8 p 0.1 IDLE 50/0/68.1

edsp 0069 01 g729r8 p 0.1 IDLE 50/0/69.1

edsp 0070 01 g729r8 p 0.1 IDLE 50/0/70.1

edsp 0071 01 g729r8 p 0.1 IDLE 50/0/71.1

edsp 0072 01 g729r8 p 0.1 IDLE 50/0/72.1

edsp 0073 01 g729r8 p 0.1 IDLE 50/0/73.1

edsp 0074 01 g729r8 p 0.1 IDLE 50/0/74.1

edsp 0075 01 g729r8 p 0.1 IDLE 50/0/75.1

edsp 0076 01 g729r8 p 0.1 IDLE 50/0/76.1

edsp 0077 01 g729r8 p 0.1 IDLE 50/0/77.1

edsp 0078 01 g729r8 p 0.1 IDLE 50/0/78.1

edsp 0079 01 g729r8 p 0.1 IDLE 50/0/79.1

edsp 0080 01 g729r8 p 0.1 IDLE 50/0/80.1

edsp 0081 01 g729r8 p 0.1 IDLE 50/0/81.1

edsp 0082 01 g729r8 p 0.1 IDLE 50/0/82.1

edsp 0083 01 g729r8 p 0.1 IDLE 50/0/83.1

edsp 0084 01 g729r8 p 0.1 IDLE 50/0/84.1

edsp 0085 01 g729r8 p 0.1 IDLE 50/0/85.1

edsp 0086 01 g729r8 p 0.1 IDLE 50/0/86.1

edsp 0087 01 g729r8 p 0.1 IDLE 50/0/87.1

edsp 0088 01 g729r8 p 0.1 IDLE 50/0/88.1

edsp 0089 01 g729r8 p 0.1 IDLE 50/0/89.1

edsp 0090 01 g729r8 p 0.1 IDLE 50/0/90.1

edsp 0091 01 g729r8 p 0.1 IDLE 50/0/91.1

edsp 0092 01 g729r8 p 0.1 IDLE 50/0/92.1

edsp 0093 01 g729r8 p 0.1 IDLE 50/0/93.1

edsp 0094 01 g729r8 p 0.1 IDLE 50/0/94.1

edsp 0095 01 g729r8 p 0.1 IDLE 50/0/95.1

edsp 0096 01 g729r8 p 0.1 IDLE 50/0/96.1

edsp 0097 01 g729r8 p 0.1 IDLE 50/0/97.1

edsp 0098 01 g729r8 p 0.1 IDLE 50/0/98.1

edsp 0099 01 g729r8 p 0.1 IDLE 50/0/99.1

edsp 0100 01 g729r8 p 0.1 IDLE 50/0/100.1

----------------------------FLEX VOICE CARD 0 -----------------------

-------

*DSP VOICE CHANNELS*

CURR STATE : (busy)inuse (b-out)busy out (bpend)busyout pending

LEGEND : (bad)bad (shut)shutdown (dpend)download pending

DSP DSP DSPWARE CURR BOOT PAK TX/RX

TYPE NUM CH CODEC VERSION STATE STATE RST AI VOICEPORT TS ABRT PACK COUNT

====== === == ========= ========== ===== ======= === == ========= == ==== ============

C5510 001 01 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 02 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 03 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 04 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 05 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 06 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 07 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 08 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 09 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 10 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 11 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 12 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 13 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 14 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 15 None 28.3.8 idle idle 0 0 0 0/0

C5510 001 16 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 01 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 02 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 03 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 04 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 05 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 06 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 07 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 08 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 09 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 10 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 11 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 12 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 13 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 14 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 15 None 28.3.8 idle idle 0 0 0 0/0

C5510 002 16 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 01 g711ulaw 28.3.8 busy idle 0 0 0/1/0 00 0 18311/18898

C5510 003 02 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 03 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 04 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 05 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 06 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 07 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 08 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 09 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 10 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 11 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 12 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 13 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 14 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 15 None 28.3.8 idle idle 0 0 0 0/0

C5510 003 16 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 01 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 02 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 03 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 04 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 05 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 06 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 07 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 08 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 09 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 10 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 11 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 12 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 13 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 14 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 15 None 28.3.8 idle idle 0 0 0 0/0

C5510 004 16 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 01 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 02 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 03 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 04 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 05 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 06 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 07 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 08 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 09 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 10 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 11 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 12 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 13 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 14 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 15 None 28.3.8 idle idle 0 0 0 0/0

C5510 005 16 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 01 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 02 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 03 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 04 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 05 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 06 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 07 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 08 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 09 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 10 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 11 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 12 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 13 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 14 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 15 None 28.3.8 idle idle 0 0 0 0/0

C5510 006 16 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 01 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 02 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 03 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 04 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 05 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 06 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 07 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 08 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 09 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 10 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 11 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 12 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 13 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 14 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 15 None 28.3.8 idle idle 0 0 0 0/0

C5510 007 16 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 01 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 02 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 03 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 04 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 05 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 06 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 07 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 08 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 09 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 10 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 11 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 12 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 13 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 14 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 15 None 28.3.8 idle idle 0 0 0 0/0

C5510 008 16 None 28.3.8 idle idle 0 0 0 0/0

*DSP SIGNALING CHANNELS*

DSP DSP DSPWARE CURR BOOT PAK TX/RX

TYPE NUM CH CODEC VERSION STATE STATE RST AI VOICEPORT TS ABRT PACK COUNT

====== === == ========= ========== ===== ======= === == ========= == ==== ============

------------------------END OF FLEX VOICE CARD 0 ----------------------------

Thanks

Ayodeji Okanlawon · ‎04-09-2013

Can we try and reset the dsps...

Use this command...

test dsp device 0 all reset

after the reset

do another test call and

send debug isdn q931

debug voip ccapi inout

I assume that this gateway is an h323 gaterway connected to cucm and you have the E1 going to the PBX

cucm---h323--gateway---e1--pbx

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts

datacenter · ‎04-09-2013

Yes, you're right.

We have here in Brazil our CUCM and one branch in Portugal using a gateway running as h323 with an e1 BRI connection to Siemens PBX.

Resetted dsp's and stil, here it follow the logs of both debugs you asked:

000283: *Apr 9 15:53:30.008: %CRYPTO-4-RECVD_PKT_INV_SPI: decaps: rec'd IPSEC packet has invalid sp

i for destaddr=62.28.245.250, prot=50, spi=0xF059AD46(4032408902), srcaddr=62.48.235.113, input inte

rface=FastEthernet0/3/0.2

000284: *Apr 9 15:53:30.032: //-1/00E8EA9D8826/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=55476914

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

cisco-ani=55476914

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=734

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

000285: *Apr 9 15:53:30.032: //-1/00E8EA9D8826/CCAPI/cc_api_call_setup_ind_common:

Interface=0x6A0FB38C, Call Info(

Calling Number=55476914,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Present

ation=Allowed),

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

Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,

Incoming Dial-peer=37, Progress Indication=NULL(0), Calling IE Present=TRUE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=31

000286: *Apr 9 15:53:30.032: //-1/00E8EA9D8826/CCAPI/ccCheckClipClir:

In: Calling Number=55476914(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowe

d)

000287: *Apr 9 15:53:30.032: //-1/00E8EA9D8826/CCAPI/ccCheckClipClir:

Out: Calling Number=55476914(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allow

ed)

000288: *Apr 9 15:53:30.032: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000289: *Apr 9 15:53:30.032: :cc_get_feature_vsa malloc success

000290: *Apr 9 15:53:30.032: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000291: *Apr 9 15:53:30.032: cc_get_feature_vsa count is 1

000292: *Apr 9 15:53:30.032: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000293: *Apr 9 15:53:30.032: :FEATURE_VSA attributes are: feature_name:0,feature_time:1826992416,fe

ature_id:20

000294: *Apr 9 15:53:30.032: //31/00E8EA9D8826/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=55476914(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=

Allowed),

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

000295: *Apr 9 15:53:30.032: //31/00E8EA9D8826/CCAPI/cc_process_call_setup_ind:

Event=0x6A5250D0

000296: *Apr 9 15:53:30.032: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Try with the demoted called number 734

000297: *

RTPTMAI022#Apr 9 15:53:30.032: //31/00E8EA9D8826/CCAPI/ccCallSetContext:

Context=0x7407FAAC

000298: *Apr 9 15:53:30.032: //31/00E8EA9D8826/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 31 with tag 37 to app "_ManagedAppProcess_Default"

000299: *Apr 9 15:53:30.032: //31/00E8EA9D8826/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

000300: *Apr 9 15:53:30.032: //31/00E8EA9D8826/CCAPI/ccCallSetupRequest:

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

Outgoing Dial-peer=6700, Params=0x74080FBC, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)

000301: *Apr 9 15:53:30.032: //31/00E8EA9D8826/CCAPI/ccCheckClipClir:

In: Calling Number=55476914(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowe

d)

000302: *Apr 9 15:53:30.036: //31/00E8EA9D8826/CCAPI/ccCheckClipClir:

Out: Calling Number=55476914(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allow

ed)

000303: *Apr 9 15:53:30.036: //31/00E8EA9D8826/CCAPI/ccCallSetupRequest:

Destination Pattern=[67].., Called Number=734, Digit Strip=TRUE

000304: *Apr 9 15:53:30.036: //31/00E8EA9D8826/CCAPI/ccCallSetupRequest:

Calling Number=55476914(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

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

Redirect Number=, Display Info=

Account Number=55476914, Final Destination Flag=TRUE,

Guid=00E8EA9D-7939-4116-8826-35010AFB0102, Outgoing Dial-peer=6700

000305: *Apr 9 15:53:30.036: //31/00E8EA9D8826/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=55476914

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

cisco-ani=55476914

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=734

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

000306: *Apr 9 15:53:30.036: //31/00E8EA9D8826/CCAPI/ccIFCallSetupRequestPrivate:

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

Call Params(Calling Number=55476914,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Pas

sed, Presentation=Allowed),

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

Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=6700, Call Count On=FA

LSE,

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

000307: *Apr 9 15:53:30.036: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000308: *Apr 9 15:53:30.036: :cc_get_feature_vsa malloc success

000309: *Apr 9 15:53:30.036: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000310: *Apr 9 15:53:30.036: cc_get_feature_vsa count is 2

000311: *Apr 9 15:53:30.036: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000312: *Apr 9 15:53:30.036: :FEATURE_VSA attributes are: feature_name:0,feature_time:1826992864,fe

ature_id:21

000313: *Apr 9 15:53:30.036: //32/00E8EA9D8826/CCAPI/ccIFCallSetupRequestPrivate:

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

000314: *Apr 9 15:53:30.036: //32/00E8EA9D8826/CCAPI/ccCallSetContext:

Context=0x74080F6C

000315: *Apr 9 15:53:30.036: //31/00E8EA9D8826/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=6700

000316: *Apr 9 15:53:30.036: ISDN BR0/1/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Calli

ng num 55476914

000317: *Apr 9 15:53:30.036: ISDN BR0/1/0 Q931: Sending SETUP callref = 0x0002 callID = 0x8004 swi

tch = basic-net3 interface = Network

000318: *Apr 9 15:53:30.040: ISDN BR0/1/0 Q931: TX -> SETUP pd = 8 callref = 0x02

Bearer Capability i = 0x8090A3

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0x89

Exclusive, B1

Progress Ind i = 0x8183 - Origination address is non-ISDN

Calling Party Number i = 0x0081, '55476914'

Plan:Unknown, Type:Unknown

Called Party Number i = 0x80, '734'

Plan:Unknown, Type:Unknown

000319: *Apr 9 15:53:30.120: ISDN BR0/1/0 Q931: RX <- CALL_PROC pd = 8 callref = 0x82

Channel ID i = 0x89

Exclusive, B1

000320: *Apr 9 15:53:30.124: //32/00E8EA9D8826/CCAPI/cc_api_call_proceeding:

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

000321: *Apr 9 15:53:30.228: ISDN BR0/1/0 Q931: RX <- ALERTING pd = 8 callref = 0x82

000322: *Apr 9 15:53:30.228: //32/00E8EA9D8826/CCAPI/cc_api_call_alert:

Interface=0x6A5477BC, Progress Indication=NULL(0), Signal Indication=NOT PRESENT(255)

000323: *Apr 9 15:53:30.228: //32/00E8EA9D8826/CCAPI/cc_api_call_alert:

Call Entry(Retry Count=0, Responsed=TRUE)

000324: *Apr 9 15:53:30.228: //31/00E8EA9D8826/CCAPI/ccCallAlert:

Progress Indication=NULL(0), Signal Indication=NOT PRESENT(255)

000325: *Apr 9 15:53:30.228: //31/00E8EA9D8826/CCAPI/ccCallAlert:

Call Entry(Responsed=TRUE, Alert Sent=TRUE)

000326: *Apr 9 15:53:30.228: //32/00E8EA9D8826/CCAPI/cc_api_get_called_ccm_detected:

CallInfo(ccm detected=0)

000327: *Apr 9 15:53:30.228: //31/00E8EA9D8826/CCAPI/ccCallNotify:

Data Bitmask=0x7, Call Id=31

000328: *Apr 9 15:53:30.228: //32/00E8EA9D8826/CCAPI/cc_api_get_called_ccm_detected:

CallInfo(ccm detected=0)

000329: *Apr 9 15:53:30.228: //31/00E8EA9D8826/CCAPI/cc_api_get_delay_xport:

CallInfo(delay xport=FALSE)

RTPTMAI022#

000330: *Apr 9 15:53:31.760: ISDN BR0/1/0 Q931: RX <- CONNECT pd = 8 callref = 0x82

Connected Number i = 0x0180, '734'

000331: *Apr 9 15:53:31.764: ISDN BR0/1/0 Q931: TX -> CONNECT_ACK pd = 8 callref = 0x02

000332: *Apr 9 15:53:31.764: //32/00E8EA9D8826/CCAPI/cc_api_call_connected:

Interface=0x6A5477BC, Data Bitmask=0x1, Progress Indication=NULL(0),

Connection Handle=0

000333: *Apr 9 15:53:31.764: //32/00E8EA9D8826/CCAPI/cc_api_call_connected:

Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)

000334: *Apr 9 15:53:31.768: //31/00E8EA9D8826/CCAPI/ccConferenceCreate:

(confID=0x741E3A30, callID1=0x1F, gcid=0-0-0-0, tag=0x0)

000335: *Apr 9 15:53:31.768: //32/00E8EA9D8826/CCAPI/ccConferenceCreate:

(confID=0x741E3A30, callID2=0x20, gcid=0-0-0-0, tag=0x0)

000336: *Apr 9 15:53:31.768: //31/00E8EA9D8826/CCAPI/ccConferenceCreate:

Conference Id=0x741E3A30, Call Id1=31, Call Id2=32, Tag=0x0

000337: *Apr 9 15:53:31.768: //31/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

000338: *Apr 9 15:53:31.768: cc_api_get_xcode_stream : 4702

000339: *Apr 9 15:53:31.768: //31/00E8EA9D8826/CCAPI/cc_api_bridge_done:

Conference Id=0x2, Source Interface=0x6A0FB38C, Source Call Id=31,

Destination Call Id=32, Disposition=0x0, Tag=0x0

000340: *Apr 9 15:53:31.768: //32/00E8EA9D8826/CCAPI/cc_api_bridge_done:

Conference Id=0x2, Source Interface=0x6A5477BC, Source Call Id=32,

Destination Call Id=31, Disposition=0x0, Tag=0xFFFFFFFF

000341: *Apr 9 15:53:31.768: //31/00E8EA9D8826/CCAPI/cc_generic_bridge_done:

Conference Id=0x2, Source Interface=0x6A5477BC, Source Call Id=32,

Destination Call Id=31, Disposition=0x0, Tag=0xFFFFFFFF

000342: *Apr 9 15:53:31.768: //31/00E8EA9D8826/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x2, Destination Call Id=32)

000343: *Apr 9 15:53:31.768: //32/00E8EA9D8826/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x2, Destination Call Id=31)

000344: *Apr 9 15:53:31.768: //32/00E8EA9D8826/CCAPI/cc_api_caps_ind:

Destination Interface=0x6A0FB38C, Destination Call Id=31, Source Call Id=32,

Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,

Modem=0x2, Codec Bytes=20, Signal Type=3)

000345: *Apr 9 15:53:31.768: //32/00E8EA9D8826/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))

000346: *Apr 9 15:53:31.768: //31/00E8EA9D8826/CCAPI/ccCallConnect:

Progress Indication=NULL(0), Data Bitmask=0x1

000347: *Apr 9 15:53:31.768: //32/00E8EA9D8826/CCAPI/cc_api_get_called_ccm_detected:

CallInfo(ccm detected=0)

RTPTMAI022#

000348: *Apr 9 15:53:31.768: //31/00E8EA9D8826/CCAPI/ccCallConnect:

Call Entry(Connected=TRUE, Responsed=TRUE)

000349: *Apr 9 15:53:31.768: //31/00E8EA9D8826/CCAPI/ccCallNotify:

Data Bitmask=0x7, Call Id=31

000350: *Apr 9 15:53:31.768: //32/00E8EA9D8826/CCAPI/cc_api_get_called_ccm_detected:

CallInfo(ccm detected=0)

000351: *Apr 9 15:53:31.772: //31/00E8EA9D8826/CCAPI/cc_process_notify_bridge_done:

Conference Id=0x2, Call Id1=31, Call Id2=32

000352: *Apr 9 15:53:32.732: //31/00E8EA9D8826/CCAPI/cc_api_call_disconnected:

Cause Value=47, Interface=0x6A0FB38C, Call Id=31

000353: *Apr 9 15:53:32.732: //31/00E8EA9D8826/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=47, Retry Count=0)

000354: *Apr 9 15:53:32.732: //31/00E8EA9D8826/CCAPI/ccConferenceDestroy:

Conference Id=0x2, Tag=0x0

000355: *Apr 9 15:53:32.732: //31/00E8EA9D8826/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x2, Source Interface=0x6A0FB38C, Source Call Id=31,

Destination Call Id=32, Disposition=0x0, Tag=0x0

000356: *Apr 9 15:53:32.732: //32/00E8EA9D8826/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x2, Source Interface=0x6A5477BC, Source Call Id=32,

Destination Call Id=31, Disposition=0x0, Tag=0x0

000357: *Apr 9 15:53:32.732: //31/00E8EA9D8826/CCAPI/cc_generic_bridge_done:

Conference Id=0x2, Source Interface=0x6A5477BC, Source Call Id=32,

Destination Call Id=31, Disposition=0x0, Tag=0x0

000358: *Apr 9 15:53:32.736: //31/00E8EA9D8826/CCAPI/ccCallDisconnect:

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

000359: *Apr 9 15:53:32.736: //31/00E8EA9D8826/CCAPI/ccCallDisconnect:

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

000360: *Apr 9 15:53:32.736: //31/00E8EA9D8826/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

000361: *Apr 9 15:53:32.736: //32/00E8EA9D8826/CCAPI/ccCallDisconnect:

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

000362: *Apr 9 15:53:32.736: //32/00E8EA9D8826/CCAPI/ccCallDisconnect:

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

000363: *Apr 9 15:53:32.736: //32/00E8EA9D8826/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

000364: *Apr 9 15:53:32.740: //31/00E8EA9D8826/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x6A0FB38C, Tag=0x0, Call Id=31,

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

000365: *Apr 9 15:53:32.740: //31/00E8EA9D8826/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

000366: *Apr 9 15:53:32.740: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

000367: *Apr 9 15:53:32.740: :cc_free_feature_vsa freeing 6CE5B118

000368: *Apr 9 15:53:32.740: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

000369: *Apr 9 15:53:32.740: vsacount in free is 1

000370: *Apr 9 15:53:32.740: ISDN BR0/1/0 Q931: TX -> DISCONNECT pd = 8 callref = 0x02

Cause i = 0x82AA - Switching equipment congestion

000371: *Apr 9 15:53:32.848: ISDN BR0/1/0 Q931: RX <- RELEASE pd = 8 callref = 0x82

Cause i = 0x82AA - Switching equipment congestion

000372: *Apr 9 15:53:32.852: ISDN BR0/1/0 Q931: TX -> RELEASE_COMP pd = 8 callref = 0x02

000373: *Apr 9 15:53:32.856: //32/00E8EA9D8826/CCAPI/cc_api_call_disconnect_done:

RTPTMAI022#Disposition=0, Interface=0x6A5477BC, Tag=0x0, Call Id=32,

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

000374: *Apr 9 15:53:32.856: //32/00E8EA9D8826/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

000375: *Apr 9 15:53:32.856: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

000376: *Apr 9 15:53:32.856: :cc_free_feature_vsa freeing 6CE5B2D8

000377: *Apr 9 15:53:32.856: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

000378: *Apr 9 15:53:32.856: vsacount in free is 0

Thanks again

Ayodeji Okanlawon · ‎04-09-2013

Ok,

From these logs, We can see that the problem is not with the ISDN leg of the call. The call is actually been disconnected by CUCM and the reason is cause 47..This is usually a media capabilities issue..

What is the codec configured on Dial-peer 37 ? (from the log it looks like its G729)

What is the region between the gateway and the IP phone that made the call 55476914. If the codec on dial-peer 37 is G729 and the region is set to G711 then you will have this issue...

Can you send your sh run..

also do another test call and send

debug h225 asn1

debug h245 asn1

debug voip ccapi inout

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts

Ayodeji Okanlawon · ‎04-10-2013

Daniel,

Did you get a chance to look at my post. Is your issue resolved now?

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts

datacenter · ‎04-12-2013

You were right!

I took a look on your post and we discovered a problem in our transcoder.

I've just fixed the codec to g729 in the incoming dialpeers of the router as our location in CUCM was already fixed to it and it solved the problem.

Thank you, rating now.

veszpremi.anton11 · ‎02-23-2016

Dear all,

I have same problem, can you inform me about the solution? thank you