Cisco Callmanager + ISDN Calls

[jan.ifkovitz]

Hi all,

some month ago i set up a Cisco 2811 with CME 8.6 IOS is c2800nm-adventerprisek9-mz.151-4.M4.bin. Inventory is VIC2-2BRI-NT/TE and PVDM2-16.

My Problem is That ISDN Calls IN and OUT have no function. When i dial out after seconds the status ist "unknown Number" (in the Phonedisplay).

The BRI an Voice-Port cfg:

interface BRI0/2/0

no ip address

isdn switch-type basic-net3

isdn tei-negotiation preserve

isdn point-to-point-setup

isdn incoming-voice voice

end

voice-port 0/2/0

compand-type a-law

cptone DE

Other Voive cfg:

voice service voip

no ip address trusted authenticate

allow-connections h323 to h323

allow-connections h323 to sip

allow-connections sip to h323

allow-connections sip to sip

!

voice class codec 1

codec preference 1 g711alaw

codec preference 2 g711ulaw

codec preference 3 g729r8

!

!

!

voice translation-rule 10

rule 1 /^894xxxx0$/ /1001/

rule 2 /^894xxxx9$/ /1002/

!

voice translation-rule 20

rule 1 /^0/ //

!

voice translation-rule 30

rule 1 /^1001$/ /89xxxx0/

rule 2 /^1002$/ /89xxxx9/

!

voice translation-rule 110

rule 1 /^2426180$/ /5000/

rule 2 /^.*/ /5000/

!

voice translation-rule 120

rule 1 /^9/ //

!

voice translation-rule 130

rule 1 /^/ /9/ type unknown unknown

rule 2 /^/ /90/ type national national

rule 3 /^/ /900/ type international international

!

voice translation-rule 200

rule 1 /^/ /0/ type unknown unknown

rule 2 /^/ /00/ type national national

rule 3 /^/ /000/ type international international

!

!

voice translation-profile ISDN-in

translate calling 200

translate called 10

!

voice translation-profile ISDN-out

translate calling 30

translate called 20

!

voice translation-profile SIP-in

translate calling 130

translate called 110

!

voice translation-profile SIP-out

translate called 120

!

!

voice-card 0

Debug ISDN Q931:

RO-Ker-01-AC-UGK#debug isdn q931

debug isdn q931 is              ON.

RO-Ker-01-AC-UGK#

000108: Aug 30 11:45:36.790 MEZ: ISDN BR0/2/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Calling num 89xxxx0

000109: Aug 30 11:45:36.794 MEZ: ISDN BR0/2/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Called num 017xxxxx061

RO-Ker-01-AC-UGK#

000110: Aug 30 11:45:42.794 MEZ: ISDN BR0/2/0 Q931: Ux_DLRelInd: DL_REL_IND received from L2

000111: Aug 30 11:45:42.798 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId 4405593DF1BE11E18007EA9D9BCFC2D0, SetupTime 11:45:42.798 MEZ Thu Aug 30 2012, PeerAddress 017xxxxxx61, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 11:45:42.798 MEZ Thu Aug 30 2012, DisconnectTime 11:45:42.798 MEZ Thu Aug 30 2012, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

RO-Ker-01-AC-UGK#

000112: Aug 30 11:45:42.798 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:08/30/2012 11:45:36.774,cgn:89xxxx0,cdn:017xxxxxx61,frs:0,fid:2,fcid:4405593DF1BE11E18007EA9D9BCFC2D0,legID:2E,bguid:4405593DF1BE11E18007EA9D9BCFC2D0

RO-Ker-01-AC-UGK#

000113: Aug 30 11:45:47.666 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId 4405593DF1BE11E18007EA9D9BCFC2D0, SetupTime 11:45:28.526 MEZ Thu Aug 30 2012, PeerAddress 1001, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 11:45:47.666 MEZ Thu Aug 30 2012, DisconnectTime 11:45:47.666 MEZ Thu Aug 30 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

RO-Ker-01-AC-UGK#

000114: Aug 30 11:45:47.666 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:08/30/2012 11:45:28.526,cgn:1001,cdn:,frs:0,fid:1,fcid:4405593DF1BE11E18007EA9D9BCFC2D0,legID:2D,bguid:4405593DF1BE11E18007EA9D9BCFC2D0

RO-Ker-01-AC-UGK#

#######################################################################################################################

Anyone an idea ? What is my problem ?

THX @ll

[Ayodeji Okanlawon]

can you send the full debug isdn q931 details.

Please rate all useful posts

"'Nature is too thin a screen, the glory of the omnipresent God bursts through it everywhere"-Ralph Waldo Emerson

[jan.ifkovitz]

Here is the ISDN q931 details:

RO-Ker-01-AC-UGK#debug isdn q931 detail

debug isdn q931 detail is       ON.

RO-Ker-01-AC-UGK#

RO-Ker-01-AC-UGK#

000115: Aug 30 12:17:59.286 MEZ: ISDN BR0/2/0 Q931d: Default type/plan 0x0 0x0 sw-type 1 Calling number 89xxxx0 isdn_map 0 mask 0x3042

000116: Aug 30 12:17:59.286 MEZ: ISDN BR0/2/0 Q931d: Calling plan/type unspecified in call_decode, so ignored.

000117: Aug 30 12:17:59.286 MEZ: ISDN BR0/2/0 Q931d: No call_decode, isdn default set

000118: Aug 30 12:17:59.286 MEZ: ISDN BR0/2/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Calling num 89xxxx0; Oct3a 0x0

000119: Aug 30 12:17:59.286 MEZ: ISDN BR0/2/0 Q931d: Default type/plan 0x0 0x0 sw-type 1 Called number 017xxxxxx061 isdn_map 0 mask 0x3042

000120: Aug 30 12:17:59.286 MEZ: ISDN BR0/2/0 Q931d: Called plan/type unspecified in call_decode, so ignored.

000121: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931d: No call_decode, isdn default set

000122: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Called num 017xxxxxx061

000123: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931d: L3_Go: source 0x0400, ces 1, event 0x0080, call id 0x8004, int id 0x0

000124: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931d: L3_Go: event 0x80  cr_len 1 cr 0

000125: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931d: L3_GetNew_NLCB: call_id 0x8004 int_id 0, ces 1

000126: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931d: L3_Go: call_id 0x8004 cr 0x0 state 26 event 0x80 ces 1

000127: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931d: L3_ProcessEvent: callref = 0x0000 CC_SETUP_REQ:U98_SetupReq(nlcb)

000128: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931d: L3_EnQueue: Event not yet processed, but queued - state 1A event 80

000129: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931d: L3_MailPkt: Request 0x0200 to callref/callid 0x8004/0000 on CES 1

000130: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931d: srl_send_l3_pak:

        source_id = Q.931 0x0300, dest_id = Q.921 0x0000, prim = DL_EST_REQ 0x0200

        priv_len = 4 int_id = 0x4A8A9B98 datasize = 16

000131: Aug 30 12:17:59.290 MEZ: ISDN BR0/2/0 Q931d: data =

000132: Aug 30 12:17:59.290 MEZ:        4A8A9B98000003000200040000010400

000133: Aug 30 12:17:59.290 MEZ:

000134: Aug 30 12:17:59.294 MEZ: ISDN BR0/2/0 Q931d: L3_state_change: callref 0x0000 old DL_IDLE_STATE, new ESTABLISH_WAIT

000135: Aug 30 12:17:59.294 MEZ: ISDN BR0/2/0 Q931d: L3IF_rx_L2_pak: received data

000136: Aug 30 12:17:59.294 MEZ:        0001

000137: Aug 30 12:17:59.294 MEZ: ISDN BR0/2/0 Q931d: srl_send_l3_pak:

        source_id = ME 0x0700, dest_id = Q.921 0x0000, prim = MDL_UI_DATA_REQ 0x0650

        priv_len = 4 int_id = 0x4A8A9B98 datasize = 21

000138: Aug 30 12:17:59.294 MEZ: ISDN BR0/2/0 Q931d: data =

RO-Ker-01-AC-UGK#

000139: Aug 30 12:17:59.294 MEZ:        4A8A9B9800000700065004003F000400

000140: Aug 30 12:17:59.294 MEZ:        0FAF8C01FF

RO-Ker-01-AC-UGK#

000141: Aug 30 12:18:01.294 MEZ: ISDN  LIFd: l3_timer: timer Expiry (0x4FEFC6DC), timer_type (0x1710)

000142: Aug 30 12:18:01.294 MEZ: ISDN  LIFd: l3_timer: Post primitive to ME MailBox 4786D60C:

000143: Aug 30 12:18:01.294 MEZ: ISDN BR0/2/0 Q931d: srl_send_l3_pak:

        source_id = ME 0x0700, dest_id = Q.921 0x0000, prim = MDL_UI_DATA_REQ 0x0650

        priv_len = 4 int_id = 0x4A8A9B98 datasize = 21

000144: Aug 30 12:18:01.294 MEZ: ISDN BR0/2/0 Q931d: data =

000145: Aug 30 12:18:01.294 MEZ:        4A8A9B9800000700065004003F000400

000146: Aug 30 12:18:01.294 MEZ:        0FA84D01FF

RO-Ker-01-AC-UGK#

000147: Aug 30 12:18:03.294 MEZ: ISDN  LIFd: l3_timer: timer Expiry (0x4FEFC6DC), timer_type (0x1710)

000148: Aug 30 12:18:03.294 MEZ: ISDN  LIFd: l3_timer: Post primitive to ME MailBox 4786D60C:

000149: Aug 30 12:18:03.294 MEZ: ISDN BR0/2/0 Q931d: srl_send_l3_pak:

        source_id = ME 0x0700, dest_id = Q.921 0x0000, prim = MDL_UI_DATA_REQ 0x0650

        priv_len = 4 int_id = 0x4A8A9B98 datasize = 21

000150: Aug 30 12:18:03.294 MEZ: ISDN BR0/2/0 Q931d: data =

000151: Aug 30 12:18:03.294 MEZ:        4A8A9B9800000700065004A43F000400

000152: Aug 30 12:18:03.294 MEZ:        0F2D1E01FF

RO-Ker-01-AC-UGK#

000153: Aug 30 12:18:05.294 MEZ: ISDN  LIFd: l3_timer: timer Expiry (0x4FEFC6DC), timer_type (0x1710)

000154: Aug 30 12:18:05.294 MEZ: ISDN  LIFd: l3_timer: Post primitive to ME MailBox 4786D60C:

000155: Aug 30 12:18:05.294 MEZ: ISDN BR0/2/0 Q931d: srl_send_l3_pak:

        source_id = ME 0x0700, dest_id = Q.921 0x0000, prim = MDL_ERR_RESP 0x0692

        priv_len = 4 int_id = 0x4A8A9B98 datasize = 17

000156: Aug 30 12:18:05.294 MEZ: ISDN BR0/2/0 Q931d: data =

000157: Aug 30 12:18:05.294 MEZ:        4A8A9B9800000700069204000001040A

000158: Aug 30 12:18:05.294 MEZ:        45

000159: Aug 30 12:18:05.294 MEZ: ISDN BR0/2/0 Q931d: L3_Go: source 0x020A, ces 1, event 0x0211, call id 0x0000, int id 0x0

000160: Aug 30 12:18:05.294 MEZ: ISDN BR0/2/0 Q931d: L3_Go: event 0x211  cr_len 0 cr 0

000161: Aug 30 12:18:05.294 MEZ: ISDN BR0/2/0 Q931d: L3_GetUser_from_ces_callid: NLCB 4A8C0660 CallId 0x0000

000162: Aug 30 12:18:05.294 MEZ: ISDN BR0/2/0 Q931d: L3_GetUser_from_ces_callid: NLCB 4A8C07A0 CallId 0x0000

000163: Aug 30 12:18:05.294 MEZ: ISDN BR0/2/0 Q931d: L3_GetUser_from_ces_callid: NLCB 4A8C02A0 CallId 0x8004

000164: Aug 30 12:18:05.294 MEZ: ISDN BR0/2/0 Q931d: L3_GetUser_from_ces_callid: found 00000000

000165: Aug 30 12:18:05.298 MEZ: ISDN BR0/2/0 Q931d: L3_GetNew_NLCB: call_id 0xFFFF int_id 0, ces 1

000166: Aug 30 12:18:05.298 MEZ: ISDN BR0/2/0 Q931d: L3_Go: call_id 0xFFFF cr 0x0 state 26 event 0x211 ces 1

000167: Aug 30 12:18:05.298 MEZ: ISDN BR0/2/0 Q931d: L3_ProcessEvent: callref = 0x0000 DL_REL_IND:Ux_DLRelInd(nlcb)

000168: Aug 30 12:18:05.298 MEZ: ISDN BR0/2/0 Q931d: L3_StopT309()

000169: Aug 30 12:18:05.298 MEZ: ISDN BR0/2/0 Q931d: L3_MailPkt: Request 0x0311 to callref/callid 0xFFFF/0000 on CES 1

000170: Aug 30 12:18:05.298 MEZ: ISDN BR0/2/0 Q931: Ux_DLRelInd: DL_REL_IND received from L2

000171: Aug 30 12:18:05.302 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId CA1F4DB9F1C211E1800CEA9D9BCFC2D0, SetupTime 12:18:05.302 MEZ Thu Aug 30 2012, PeerAddress 017xxxxx061, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 12:18:05.302 MEZ Thu Aug 30 2012, DisconnectTime 12:18:05.302 MEZ Thu Aug 30 2012, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

RO-Ker-01-AC-UGK#

000172: Aug 30 12:18:05.302 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:08/30/2012 12:17:59.270,cgn:89xxxx0,cdn:017xxxxx061,frs:0,fid:4,fcid:CA1F4DB9F1C211E1800CEA9D9BCFC2D0,legID:60,bguid:CA1F4DB9F1C211E1800CEA9D9BCFC2D0

RO-Ker-01-AC-UGK#

000173: Aug 30 12:18:12.222 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId CA1F4DB9F1C211E1800CEA9D9BCFC2D0, SetupTime 12:17:51.492 MEZ Thu Aug 30 2012, PeerAddress 1001, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 12:18:12.222 MEZ Thu Aug 30 2012, DisconnectTime 12:18:12.222 MEZ Thu Aug 30 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

RO-Ker-01-AC-UGK#

000174: Aug 30 12:18:12.222 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:08/30/2012 12:17:51.490,cgn:1001,cdn:,frs:0,fid:3,fcid:CA1F4DB9F1C211E1800CEA9D9BCFC2D0,legID:5F,bguid:CA1F4DB9F1C211E1800CEA9D9BCFC2D0

RO-Ker-01-AC-UGK#

[Ayodeji Okanlawon]

Can you use this command to check the status of your isdn. please post the output

sh isdn status

Do another test call and send me "debug isdn q931" without the detail. I cant see the call setup in the previous log

Please rate all useful posts

"'Nature is too thin a screen, the glory of the omnipresent God bursts through it everywhere"-Ralph Waldo Emerson

[jan.ifkovitz]

show ISDN status:

RO-Ker-01-AC-UGK#sh isdn status

Global ISDN Switchtype = basic-net3

ISDN BRI0/2/0 interface

        dsl 8, interface ISDN Switchtype = basic-net3

    Layer 1 Status:

        ACTIVE

    Layer 2 Status:

        Layer 2 NOT Activated

    Layer 3 Status:

        0 Active Layer 3 Call(s)

    Active dsl 8 CCBs = 0

    The Free Channel Mask:  0x80000003

ISDN BRI0/2/1 interface

        dsl 9, interface ISDN Switchtype = basic-net3

    Layer 1 Status:

        DEACTIVATED

    Layer 2 Status:

        Layer 2 NOT Activated

    Layer 3 Status:

        0 Active Layer 3 Call(s)

    Active dsl 9 CCBs = 0

    The Free Channel Mask:  0x80000003

    Total Allocated ISDN CCBs = 0

RO-Ker-01-AC-UGK#

ISDN Q931 Debug:

Typing the numer and press "#"

RO-Ker-01-AC-UGK#

000175: Aug 30 12:48:35.166 MEZ: ISDN BR0/2/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Calling num 89xxxx0

000176: Aug 30 12:48:35.170 MEZ: ISDN BR0/2/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Called num 17xxxxx061

RO-Ker-01-AC-UGK#

"Unkown Number" (in Display)

000177: Aug 30 12:48:41.170 MEZ: ISDN BR0/2/0 Q931: Ux_DLRelInd: DL_REL_IND received from L2

000178: Aug 30 12:48:41.174 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId 105202DDF1C711E18011EA9D9BCFC2D0, SetupTime 12:48:41.174 MEZ Thu Aug 30 2012, PeerAddress 17630314061, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 12:48:41.174 MEZ Thu Aug 30 2012, DisconnectTime 12:48:41.174 MEZ Thu Aug 30 2012, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

RO-Ker-01-AC-UGK#

000179: Aug 30 12:48:41.178 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:08/30/2012 12:48:35.150,cgn:89xxxx00,cdn:176xxxxx1,frs:0,fid:6,fcid:105202DDF1C711E18011EA9D9BCFC2D0,legID:8E,bguid:105202DDF1C711E18011EA9D9BCFC2D0

RO-Ker-01-AC-UGK#

Hang UP:

000180: Aug 30 12:48:48.378 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId 105202DDF1C711E18011EA9D9BCFC2D0, SetupTime 12:48:27.258 MEZ Thu Aug 30 2012, PeerAddress 1001, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 12:48:48.378 MEZ Thu Aug 30 2012, DisconnectTime 12:48:48.378 MEZ Thu Aug 30 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

RO-Ker-01-AC-UGK#

000181: Aug 30 12:48:48.382 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:08/30/2012 12:48:27.250,cgn:1001,cdn:,frs:0,fid:5,fcid:105202DDF1C711E18011EA9D9BCFC2D0,legID:8D,bguid:105202DDF1C711E18011EA9D9BCFC2D0

RO-Ker-01-AC-UGK#

##############################################################################################

That is all i see.

[Ayodeji Okanlawon · Accepted solution]

Your ISDN BRI layer 2 status is down.

ISDN BRI0/2/0 interface

        dsl 8, interface ISDN Switchtype = basic-net3

    Layer 1 Status:

        ACTIVE

   Layer 2 Status:

        Layer 2 NOT Activated

    Layer 3 Status:

        0 Active Layer 3 Call(s)

    Active dsl 8 CCBs = 0

    The Free Channel Mask:  0x80000003

Have you configured the correct switch type? Is this the correct switch type your provider supports?

Can you post a debug isdn q921.

Please rate all useful posts

"'Nature is too thin a screen, the glory of the omnipresent God bursts through it everywhere"-Ralph Waldo Emerson

[jan.ifkovitz]

I think that that is correct:

basic-net3

NET3 switch type for UK, Europe, Asia, and Australia

because the layer 2 Problem... it schould be normal its only active by call.

##################################################################################

26.04.2012 04:03                             (als Antwort auf: mitchell.drage)

Re: Cisco 2811 CME 8.6 ISDN /// VIC2-2BRI NT/TE

Mitchell, as discussed so many times in similar thread. that is normal with BRI.

CO switch removes power to the circuit, and that state is normal.

The router is supposed to be able to re-activate layer 3 when placing a call. This can be observed with "debug bri".

##################################################################################

Debug ISDN Q921:

000196: Aug 30 13:30:43.641 MEZ: ISDN BR0/2/0 Q921: User RX <- RRp sapi=0 tei=77 nr=1

RO-Ker-01-AC-UGK#

000197: Aug 30 13:30:53.641 MEZ: ISDN BR0/2/0 Q921: User RX <- RRp sapi=0 tei=77 nr=1

RO-Ker-01-AC-UGK#

000198: Aug 30 13:30:54.957 MEZ: ISDN BR0/2/0 Q921: User TX -> IDREQ ri=21653 ai=127

RO-Ker-01-AC-UGK#

000199: Aug 30 13:30:56.957 MEZ: ISDN BR0/2/0 Q921: User TX -> IDREQ ri=40422 ai=127

RO-Ker-01-AC-UGK#

000200: Aug 30 13:30:58.957 MEZ: ISDN BR0/2/0 Q921: User TX -> IDREQ ri=31815 ai=127

RO-Ker-01-AC-UGK#

000201: Aug 30 13:31:00.961 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId FA5407CCF1CC11E1801BEA9D9BCFC2D0, SetupTime 13:31:00.961 MEZ Thu Aug 30 2012, PeerAddress 017xxxxx61, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 13:31:00.961 MEZ Thu Aug 30 2012, DisconnectTime 13:31:00.961 MEZ Thu Aug 30 2012, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

RO-Ker-01-AC-UGK#

000202: Aug 30 13:31:00.961 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:08/30/2012 13:30:54.937,cgn:89xxxx00,cdn:017xxxxx61,frs:0,fid:10,fcid:FA5407CCF1CC11E1801BEA9D9BCFC2D0,legID:CF,bguid:FA5407CCF1CC11E1801BEA9D9BCFC2D0

RO-Ker-01-AC-UGK#

000203: Aug 30 13:31:03.681 MEZ: ISDN BR0/2/0 Q921: User RX <- RRp sapi=0 tei=77 nr=1

000204: Aug 30 13:31:03.689 MEZ: ISDN BR0/2/0 Q921: User RX <- RRf sapi=0 tei=77 nr=1

RO-Ker-01-AC-UGK#

000205: Aug 30 13:31:13.641 MEZ: ISDN BR0/2/0 Q921: User RX <- RRp sapi=0 tei=77 nr=1

RO-Ker-01-AC-UGK#

000206: Aug 30 13:31:20.253 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId FA5407CCF1CC11E1801BEA9D9BCFC2D0, SetupTime 13:30:47.333 MEZ Thu Aug 30 2012, PeerAddress 1001, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 13:31:20.253 MEZ Thu Aug 30 2012, DisconnectTime 13:31:20.253 MEZ Thu Aug 30 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

RO-Ker-01-AC-UGK#

000207: Aug 30 13:31:20.253 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:08/30/2012 13:30:47.333,cgn:1001,cdn:,frs:0,fid:9,fcid:FA5407CCF1CC11E1801BEA9D9BCFC2D0,legID:CE,bguid:FA5407CCF1CC11E1801BEA9D9BCFC2D0

RO-Ker-01-AC-UGK#

000208: Aug 30 13:31:23.641 MEZ: ISDN BR0/2/0 Q921: User RX <- RRp sapi=0 tei=77 nr=1

RO-Ker-01-AC-UGK#

000209: Aug 30 13:31:33.641 MEZ: ISDN BR0/2/0 Q921: User RX <- RRp sapi=0 tei=77 nr=1

debug bri:

RO-Ker-01-AC-UGK# debug bri

Basic Rate network interface debugging is on

RO-Ker-01-AC-UGK#

RO-Ker-01-AC-UGK#

000210: Aug 30 13:45:00.181 MEZ: dchan tx start

000211: Aug 30 13:45:00.189 MEZ: dchan tx intr

RO-Ker-01-AC-UGK#

000212: Aug 30 13:45:02.181 MEZ: dchan tx start

000213: Aug 30 13:45:02.185 MEZ: dchan tx intr

RO-Ker-01-AC-UGK#

000214: Aug 30 13:45:04.181 MEZ: dchan tx start

000215: Aug 30 13:45:04.185 MEZ: dchan tx intr

RO-Ker-01-AC-UGK#

000216: Aug 30 13:45:06.185 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId F1335F2DF1CE11E18020EA9D9BCFC2D0, SetupTime 13:45:06.185 MEZ Thu Aug 30 2012, PeerAddress 01xxxxxx61, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 13:45:06.185 MEZ Thu Aug 30 2012, DisconnectTime 13:45:06.185 MEZ Thu Aug 30 2012, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

RO-Ker-01-AC-UGK#

000217: Aug 30 13:45:06.185 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:08/30/2012 13:45:00.165,cgn:89xxxx0,cdn:017xxxxx61,frs:0,fid:12,fcid:F1335F2DF1CE11E18020EA9D9BCFC2D0,legID:E7,bguid:F1335F2DF1CE11E18020EA9D9BCFC2D0

RO-Ker-01-AC-UGK#

000218: Aug 30 13:45:16.941 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId F1335F2DF1CE11E18020EA9D9BCFC2D0, SetupTime 13:44:51.011 MEZ Thu Aug 30 2012, PeerAddress 1001, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 13:45:16.941 MEZ Thu Aug 30 2012, DisconnectTime 13:45:16.941 MEZ Thu Aug 30 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

RO-Ker-01-AC-UGK#

000219: Aug 30 13:45:16.945 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:08/30/2012 13:44:51.013,cgn:1001,cdn:,frs:0,fid:11,fcid:F1335F2DF1CE11E18020EA9D9BCFC2D0,legID:E6,bguid:F1335F2DF1CE11E18020EA9D9BCFC2D0

RO-Ker-01-AC-UGK#

[jan.ifkovitz]

No one any idea ?

[paolo bevilacqua]

Please send "debug isdn q931" only. No other debugs or output should be included.

[jan.ifkovitz]

RO-Ker-01-AC-UGK#un all

All possible debugging has been turned off

RO-Ker-01-AC-UGK#

RO-Ker-01-AC-UGK#debug isdn q931

debug isdn q931 is              ON.

RO-Ker-01-AC-UGK#term mon

########################################################

000500: Sep  4 10:33:35.847 MEZ: ISDN BR0/2/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Calling num 894xxx09

000501: Sep  4 10:33:35.851 MEZ: ISDN BR0/2/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Called num 017xxxxx061

000502: Sep  4 10:33:41.851 MEZ: ISDN BR0/2/0 Q931: Ux_DLRelInd: DL_REL_IND received from L2

000503: Sep  4 10:33:41.855 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId BB78D69F5A211E180BEEA9D9BCFC2D0, SetupTime 10:33:41.855 MEZ Tue Sep 4 2012, PeerAddress 0176xxxxx061, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 10:33:41.855 MEZ Tue Sep 4 2012, DisconnectTime 10:33:41.855 MEZ Tue Sep 4 2012, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

000504: Sep  4 10:33:41.855 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:09/04/2012 10:33:35.827,cgn:89xxx00,cdn:0176xxxx4061,frs:0,fid:49,fcid:BB78D69F5A211E180BEEA9D9BCFC2D0,legID:2906,bguid:0BB78D69F5A211E180BEEA9D9BCFC2D0

000505: Sep  4 10:33:49.967 MEZ: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId BB78D69F5A211E180BEEA9D9BCFC2D0, SetupTime 10:33:32.797 MEZ Tue Sep 4 2012, PeerAddress 1001, PeerSubAddress , DisconnectCause 29  , DisconnectText temporary failure (41), ConnectTime 10:33:49.967 MEZ Tue Sep 4 2012, DisconnectTime 10:33:49.967 MEZ Tue Sep 4 2012, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

000506: Sep  4 10:33:49.967 MEZ: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:09/04/2012 10:33:32.799,cgn:1001,cdn:,frs:0,fid:48,fcid:BB78D69F5A211E180BEEA9D9BCFC2D0,legID:2905,bguid:0BB78D69F5A211E180BEEA9D9BCFC2D0

##########################################################################

[Kevin Monteiro]

Hi Jan,

can you share the output of debug voip ccapi inout & debug isdn q931.

(Kindly share the whole output with the outgoing - setup msg)

Regards,

Kevin

[jan.ifkovitz]

yes i can tomorrow... sorry for the late answer. i left for a short trip

[jan.ifkovitz]

This is the Output of "debug voip ccapi inout". I put this into a file because ist too much... for the debug "isdn q931" see above

[jan.ifkovitz]

Some other idea... what is if the ISDN Port is OK but the Call routing is wrong ? Means, is there a failure in my tranlation rules ?

tonight i called intern the number 1002 an it has the same efect. "unknown Number" because the telefon was not reachable.

[Ayodeji Okanlawon]

Jan,

Looking at the trace, It doesnt look like its your translation rule. It looks like you have a problem with the ISDN.

Here is the detail of the trace..

1. Call setup request come in to the gtateway fro extension 1001

cisco-ani=1001

   cisco-anitype=0

   cisco-aniplan=0

   cisco-anipi=0

   cisco-anisi=0

   dest=

2. The gateway request a call setup to the ISDN via dial-peer 10.

Here the calling number is xlated from 1001 to 89491700

Guid=965F284C-F5A2-11E1-80C3-EA9D9BCFC2D0, Outgoing Dial-peer=10

000584: Sep  4 10:37:28.479 MEZ: //10511/965F284C80C3/CCAPI/cc_api_display_ie_subfields:

   ccCallSetupRequest:

   cisco-username=

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

   cisco-ani=89491700

   cisco-anitype=0

   cisco-aniplan=0

   cisco-anipi=0

   cisco-anisi=0

   dest=017630314061

3. Immediately after the callsetup request is sent to the ISDN by the gateway, the call drops

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

000593: Sep  4 10:37:28.483 MEZ: //10512/965F284C80C3/CCAPI/ccCallSetContext:

   Context=0x4FF45224

000594: Sep  4 10:37:28.483 MEZ: //10511/965F284C80C3/CCAPI/ccSaveDialpeerTag:

RO-Ker-01-AC-UGK#Outgoing Dial-peer=10

RO-Ker-01-AC-UGK#

000595: Sep  4 10:37:34.507 MEZ: //10512/965F284C80C3/CCAPI/cc_api_call_disconnected:

   Cause Value=41, Interface=0x4C0EF0E8, Call Id=10512 SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
000593: Sep  4 10:37:28.483 MEZ: //10512/965F284C80C3/CCAPI/ccCallSetContext:
   Context=0x4FF45224
000594: Sep  4 10:37:28.483 MEZ: //10511/965F284C80C3/CCAPI/ccSaveDialpeerTag:

RO-Ker-01-AC-UGK#Outgoing Dial-peer=10
RO-Ker-01-AC-UGK#
000595: Sep  4 10:37:34.507 MEZ: //10512/965F284C80C3/CCAPI/cc_api_call_disconnected:
   Cause Value=41, Interface=0x4C0EF0E8, Call Id=10512

This shows that there is a problem with the ISDN side.

Please rate all useful posts

"'Nature is too thin a screen, the glory of the omnipresent God bursts through it everywhere"-Ralph Waldo Emerson