[cisco-voip] MGCP to PSTN - 0x80A9 - Temporary failure?

Stephen Greszczyszyn sgreszcz at gmail.com
Wed Feb 3 08:29:12 EST 2010


Hi there,

I'm working on a lab scenario where I'm making calls from CUCM7 via an
IOS MGCP gateway out to another router simulating the PSTN.  The call
connects OK, but after about 1 minute - 2 minutes, the call drops.  I
have tried the same test through another H.323 gateway and the call
stays up fine.  Running 12.4(24)T2 on the MGCP gateway.  Here is the
information:

MGCP gateway:
Feb  3 11:53:33.851: ISDN Se0/1/0:15 Q931: TX -> DISCONNECT pd = 8
callref = 0x0007
       Cause i = 0x80A9 - Temporary failure

PSTN:
*Feb  3 11:59:57.011: ISDN Se0/3/0:15 Q931: RX <- DISCONNECT pd = 8
callref = 0x0007
       Cause i = 0x80A9 - Temporary failure
*Feb  3 11:59:57.015: %ISDN-6-DISCONNECT: Interface Serial0/3/0:0
disconnected from +16178631002 , call lasted 72 seconds

I did some searching on this list and there were some references to
faulty DSPs.  Mine seem to look OK?

MGCP:
br1#sh voice dsp group all
DSP groups on slot 0:
dsp 1:
 State: UP, firmware: 24.3.2
 Max signal/voice channel: 16/16
 Max credits: 240
 num_of_sig_chnls_allocated: 8
 Transcoding channels allocated: 0
 Group: FLEX_GROUP_VOICE, complexity: FLEX
   Shared credits: 225, reserved credits: 0
   Signaling channels allocated: 8
   Voice channels allocated: 1
   Credits used: 15
   Voice channels:
     Ch01: voice port: 0/1/0:15.1, codec: g711ulaw, credits allocated: 15

PSTN:
WAN_PSTN#sh voice dsp group all
DSP groups on slot 0:
dsp 1:
 State: UP, firmware: 24.3.2
 Max signal/voice channel: 16/16
 Max credits: 240
 num_of_sig_chnls_allocated: 16
 Transcoding channels allocated: 0
 Group: FLEX_GROUP_VOICE, complexity: FLEX
   Shared credits: 225, reserved credits: 0
   Signaling channels allocated: 16
   Voice channels allocated: 1
   Credits used: 15
   Voice channels:
     Ch01: voice port: 0/3/0:15.1, codec: g711ulaw, credits allocated: 15

I also get some weird stuff around the time of call failure when I
enable MGCP debugging:

debug mgcp errors
debug mgcp packet

It seems as though my MGCP gateway tells CUCM to drop the call and
also sends the disconnect to the PSTN:

MGCP:
Feb  3 13:09:00.557: ISDN Se0/1/0:15 Q921: User TX -> INFO sapi=0
tei=0, ns=14 nr=15
Feb  3 13:09:00.557: ISDN Se0/1/0:15 Q931: DISCONNECT pd = 8  callref = 0x0003
       Cause i = 0x80A9 - Temporary failure

PSTN:
*Feb  3 13:15:23.583: ISDN Se0/3/0:15 Q921: Net RX <- INFO sapi=0
tei=0, ns=14 nr=15
*Feb  3 13:15:23.583: ISDN Se0/3/0:15 Q931: DISCONNECT pd = 8  callref = 0x0003
       Cause i = 0x80A9 - Temporary failure

(Previous call traces - MGCP debug)

Feb  3 12:52:45.713: Following traceback is for INFO ONLY. -Traceback=
0x40F3126Cz 0x40F5BA6Cz 0x40F4296Cz 0x40F3ABC4z 0x40F333D4z
0x40EEED44z 0x40EF43F0z 0x435D69D8z 0x435D69BCz
Feb  3 12:52:45.717: MGCP Packet sent to 10.10.210.11:2427--->
DLCX 205358175 S0/SU1/DS1-0/1 at br1.proctorlabs.com MGCP 0.1
C: D000000002a512c9000000F500000002
I: 3
P: PS=5000, OS=800000, PR=4978, OR=796480, PL=22, JI=7, LA=0
E: 502
<---

Feb  3 12:52:45.725: MGCP Packet received from 10.10.210.11:2427--->
200 205358175
<---

Feb  3 12:52:45.725:
//-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid
parameter (pkt 0x4A428FE0 pkt->mgcp_parm_lines 0x00000000)
Feb  3 12:52:45.733: ISDN Se0/1/0:15 Q931: TX -> DISCONNECT pd = 8
callref = 0x0002
       Cause i = 0x80A9 - Temporary failure
Feb  3 12:52:45.745: ISDN Se0/1/0:15 Q931: RX <- RELEASE pd = 8
callref = 0x8002
Feb  3 12:52:45.757: ISDN Se0/1/0:15 Q931: TX -> RELEASE_COMP pd = 8
callref = 0x0002
Feb  3 12:53:04.977: MGCP Packet sent to 10.10.210.11:2427--->
NTFY 205358176 *@br1.proctorlabs.com MGCP 0.1
X: 0
O:
<---

In the CUCM traces, it isn't clear to me why the call is being dumped:

02/03/2010 12:52:45.690 CCM|MGCPHandler received msg from: 10.10.110.2
DLCX 205358175 S0/SU1/DS1-0/1 at br1.proctorlabs.com MGCP 0.1
C: D000000002a512c9000000F500000002
I: 3
P: PS=5000, OS=800000, PR=4978, OR=796480, PL=22, JI=7, LA=0
E: 502
|<CLID::StandAloneCluster><NID::10.10.210.11><CT::2,100,132,1.837><IP::10.10.110.2><DEV::><LVL::Significant><MASK::2000>
02/03/2010 12:52:45.690
CCM|<CLID::StandAloneCluster><NID::10.10.210.11><CT::2,100,132,1.837><MN::MGCPEndPoint><MV::S0/SU1/DS1-0/1 at br1.proctorlabs.com><DEV::><LVL::All><MASK::ffff>
02/03/2010 12:52:45.691 CCM|MGCPHandler send msg SUCCESSFULLY to: 10.10.110.2
200 205358175
|<CLID::StandAloneCluster><NID::10.10.210.11><CT::2,100,132,1.837><IP::10.10.110.2><DEV::S0/SU1 at br1.proctorlabs.com><LVL::Significant><MASK::2000>
02/03/2010 12:52:45.692 CCM|ConnectionManager -
wait_AuDisconnectRequest(44372680,44372681),disconnectType(1),
IFHandling(0,0)|<CLID::StandAloneCluster><NID::10.10.210.11><CT::2,100,132,1.837><IP::10.10.110.2><DEV::S0/SU1 at br1.proctorlabs.com><LVL::Arbitrary><MASK::0800>
02/03/2010 12:52:45.692 CCM|ConnectionManager -
storeMediaInfo(44372680): EXISTING ENTRY DISCOVERED,
size=2|<CLID::StandAloneCluster><NID::10.10.210.11><CT::2,100,132,1.837><IP::10.10.110.2><DEV::S0/SU1 at br1.proctorlabs.com><LVL::Arbitrary><MASK::0800>
02/03/2010 12:52:45.692 CCM|ConnectionManager -
storeMediaInfo(44372681): EXISTING ENTRY DISCOVERED,
size=2|<CLID::StandAloneCluster><NID::10.10.210.11><CT::2,100,132,1.837><IP::10.10.110.2><DEV::S0/SU1 at br1.proctorlabs.com><LVL::Arbitrary><MASK::0800>
02/03/2010 12:52:45.692 CCM|MediaCoordinator -
wait_AuDisconnectRequest,CI(44372680,44372681),IFCreated(1,1)|<CLID::StandAloneCluster><NID::10.10.210.11><CT::2,100,132,1.837><IP::10.10.110.2><DEV::S0/SU1 at br1.proctorlabs.com><LVL::Significant><MASK::0800>
02/03/2010 12:52:45.692 CCM|MediaCoordinator -
wait_AuDisconnectRequest - sending disconnect to
MediaManager(48)|<CLID::StandAloneCluster><NID::10.10.210.11><CT::2,100,132,1.837><IP::10.10.110.2><DEV::S0/SU1 at br1.proctorlabs.com><LVL::Significant><MASK::0800>

...

02/03/2010 12:52:45.694 CCM|MGCPpn9d - Dump portInfo table:
 portInfo[00] endpoint=S0/SU1/DS1-0/1 at br1.proctorlabs.com, ci=44372681
 portInfo[01] endpoint=S0/SU1/DS1-0/2 at br1.proctorlabs.com, ci=0
 portInfo[02] endpoint=S0/SU1/DS1-0/3 at br1.proctorlabs.com, ci=0

...

02/03/2010 12:52:45.698 CCM|Out Message -- PriDisconnectMsg --
Protocol= PriEuroProtocol|<CLID::StandAloneCluster><NID::10.10.210.11><LVL::Significant><MASK::0040>
02/03/2010 12:52:45.698 CCM|Ie - Q931CauseIe IEData= 08 02 80 A9
|<CLID::StandAloneCluster><NID::10.10.210.11><LVL::State
Transition><MASK::0040>

 Thanks for any suggestions!


More information about the cisco-voip mailing list