Need help in Troubleshooting ISDN over chap authentication !!!

Dr.X · ‎03-03-2014

hi all ,

i ahve router to get negotiated ip from isp by issdn bri interface :

i have :

Router#sh isdn st

Global ISDN Switchtype = basic-net3

ISDN BRI0 interface

dsl 0, interface ISDN Switchtype = basic-net3

Layer 1 Status:

ACTIVE

Layer 2 Status:

TEI = 77, Ces = 1, SAPI = 0, State = MULTIPLE_FRAME_ESTABLISHEDi have

Layer 3 Status:

0 Active Layer 3 Call(s)

Active dsl 0 CCBs = 0

The Free Channel Mask: 0x80000003

Internal Multi-drop Termination is ENABLED

Total Allocated ISDN CCBs = 0

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

it seems that i have L3 issue ,

i can see that L1,L2 is fine , but L3 dont have active calls ,

i tried ping to initittae traffic but still no benefit :

i have the following running config :

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

Building configuration...

Current configuration : 3923 bytes

ip source-route

!

ip cef

no ipv6 cef

!

multilink bundle-name authenticated

!

isdn switch-type basic-net3

!

license udi pid CISCO892-K9 sn FCZ1739906V

!

username admin123 privilege 15 secret 4 ruifghdjksdjkfgsdhjfgsdjfghsd

username xxxxxxxxxx password 0 xxxxxxxx

!

interface BRI0

ip address negotiated

encapsulation ppp

dialer pool-member 1

isdn switch-type basic-net3

isdn termination multidrop

isdn point-to-point-setup

ppp authentication chap

ppp multilink

!

interface GigabitEthernet0

no ip address

shutdown

duplex auto

speed auto

!

interface Dialer1

ip address negotiated

encapsulation ppp

dialer in-band

dialer string 600200000

dialer string 600200005

dialer load-threshold 1 either

dialer-group 1

keepalive 10 3

ppp authentication chap

ppp chap hostname xxxxx

ppp multilink

!

ip forward-protocol nd

!

ip route 0.0.0.0 0.0.0.0 Dialer1

!

dialer-list 1 protocol ip permit

!

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

i have debug here :

Router#debug isdn q921

debug isdn q921 is ON.

Router#debug isdn q931

debug isdn q931 is ON.

Router#

Router#ping 64.55.45.8

Type escape sequence to abort.

Sending 5, 100-byte ICMP Echos to x.x.x.x, timeout is 2 seconds:

Mar 3 09:35:33.359: ISDN BR0 Q921: User TX -> RRp sapi=0 tei=77 nr=0

Mar 3 09:35:33.363: ISDN BR0 Q921: User RX <- RRp sapi=0 tei=77 nr=0

Mar 3 09:35:33.363: ISDN BR0 Q921: User TX -> RRf sapi=0 tei=77 nr=0

Mar 3 09:35:33.375: ISDN BR0 Q921: User RX <- RRf sapi=0 tei=77 nr=0.....

Success rate is 0 percent (0/5)

Router#

Mar 3 09:35:43.379: ISDN BR0 Q921: User TX -> RRp sapi=0 tei=77 nr=0

Mar 3 09:35:43.379: ISDN BR0 Q921: User RX <- RRp sapi=0 tei=77 nr=0

Mar 3 09:35:43.379: ISDN BR0 Q921: User TX -> RRf sapi=0 tei=77 nr=0

Mar 3 09:35:43.399: ISDN BR0 Q921: User RX <- RRf sapi=0 tei=77 nr=0

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

hereis debug all isdn result :

Router#ping

Mar 3 09:36:43.471: ISDN L2_CODE: isdn_l2_process: TIMER_EVENT

Mar 3 09:36:43.471: ISDN L2_CODE: l2_timer()

Mar 3 09:36:43.471: ISDN Q921d: l2_timer: timer (0x87EC445C), timer_type (0x1250)

Mar 3 09:36:43.471: ISDN BR0 L2_CODE: L2IF_GetDriverID()

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_SendprimPkt:

source_id (0x20A), state (0x7), dest_id (0x200), event (0x140),

sapi (0x0), ces (0x1), call_id (0x0),

chan_id (0x0), int_id (0x87749534), free_p (0x1), pkt (0x877752D0), msecs (0x0), priority (0xA)

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_SendPkt:

source_id (0x20A), state (0x7),

dest_id (0x200), event (0x140),

sapi (0x0), ces (0x1), call_id (0x0),

chan_id (0x0), int_id (0x87749534), free_pkt (0x1),

pkt (0x87DBCBA4), msecs (0x0), priority (0xA)

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F4A5818), size (4)

Mar 3 09:36:43.471: 009B0101--0000009D

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_SendPkt: Sending from L2

dest_id = 0

Router#ping 131, source_id = 522, prim = 140

priv_ln = 4, int_id = -2022402764

datastart = 1F4A5808, netstart = 1F4A5818, datasize = 20

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F4A5808), size (20)

Mar 3 09:36:43.471: 877495340000020A0140040000010603009B0101--000002BC

Mar 3 09:36:43.471: ISDN Q921d: isdn_l2d_srq_process: QUEUE_EVENT

Mar 3 09:36:43.471: ISDN BR0 PACKET: handle_l2d_srq_mail: received:

dest_id = 0x0000, source_id = 0x020A, prim = 0x0140

priv_ln = 4, int_id = 0x87749534, datasize = 4

line_status[0] = IF_ACTIVE

Mar 3 09:36:43.471: ISDN BR0 Q921r: User TX ->

Mar 3 09:36:43.471: 009B0101--0000009D

Mar 3 09:36:43.471: ISDN Q921d: isdn_l2d_srq_process: event_count 1

Mar 3 09:36:43.471: ISDN L2_CODE: isdn_l2_process: state (0x0), event_count (1)

Mar 3 09:36:43.471: ISDN Q921d: isdn_from_driver_process: QUEUE_EVENT

Mar 3 09:36:43.471: ISDN BR0 Q921r: User RX <-

Mar 3 09:36:43.471: 029B0101--0000009F

Mar 3 09:36:43.471: ISDN BR0 Q921d: process_rxdata: Frame sent to L2

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_SendPkt:

source_id (0x200), state (0x0),

dest_id (0x20A), event (0x141),

sapi (0x0), ces (0x0), call_id (0x0),

chan_id (0x0), int_id (0x87749534), free_pkt (0x1),

pkt (0x8A127D94), msecs (0x0), priority (0xA)

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F2D2CF4), size (4)

Mar 3 09:36:43.471: 029B0101--0000009F

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_SendPkt: Sending from L2

dest_id = 0, source_id = 512, prim = 141

priv_ln = 4, int_id = -2022402764

datastart = 1F2D2CE4, netstart = 1F2D2CF4, datasize = 20

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F2D2CE4), size (20)

Mar 3 09:36:43.471: 87749534000002000141040000000600029B0101--000002B1

Mar 3 09:36:43.471: ISDN Q921d: isdn_from_driver_process: event_count 1

Mar 3 09:36:43.471: ISDN L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0

Mar 3 09:36:43.471: ISDN BR0 PACKET: handle_mail: received:

dest_id = 0, source_id = 512, prim = 141

priv_ln = 4, int_id = -2022402764, datasize = 20

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F2D2CF4), size (20)

Mar 3 09:36:43.471: 029B0101266DA049E97630DD0000000000000000--00000487

Mar 3 09:36:43.471: ISDN BR0 PACKET: handle_mail: received data =

Mar 3 09:36:43.471: 029B0101--0000009F

Mar 3 09:36:43.471: ISDN BR0 L2_CODE: L2IF_GetDriverID()

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_SendprimPkt:

source_id (0x20A), state (0x8), dest_id (0x200), event (0x140),

sapi (0x0), ces (0x1), call_id (0x0),

chan_id (0x0), int_id (0x87749534), free_p (0x1), pkt (0x877752B0), msecs (0x0), priority (0xA)

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_SendPkt:

source_id (0x20A), state (0x8),

dest_id (0x200), event (0x140),

sapi (0x0), ces (0x1), call_id (0x0),

chan_id (0x0), int_id (0x87749534), free_pkt (0x1),

pkt (0x87DBD060), msecs (0x0), priority (0xA)

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F4A5958), size (4)

Mar 3 09:36:43.471: 029B0101--0000009F

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_SendPkt: Sending from L2

dest_id = 0, source_id = 522, prim = 140

priv_ln = 4, int_id = -2022402764

datastart = 1F4A5948, netstart = 1F4A5958, datasize = 20

Mar 3 09:36:43.471: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F4A5948), size (20)

Mar 3 09:36:43.471: 877495340000020A0140040000010603029B0101--000002BE

Mar 3 09:36:43.471: ISDN L2_CODE: isdn_l2_process: TIMER_EVENT

Mar 3 09:36:43.471: ISDN Q921d: isdn_l2d_srq_process: QUEUE_EVENT

Mar 3 09:36:43.471: ISDN BR0 PACKET: handle_l2d_srq_mail: received:

dest_id = 0x0000, source_id = 0x020A, prim = 0x0140

priv_ln = 4, int_id = 0x87749534, datasize = 4

line_status[0] = IF_ACTIVE

Mar 3 09:36:43.475: ISDN BR0 Q921r: User TX ->

Mar 3 09:36:43.475: 029B0101--0000009F

Mar 3 09:36:43.475: ISDN Q921d: isdn_l2d_srq_process: event_count 1

Mar 3 09:36:43.475: ISDN L2_CODE: isdn_l2_process: state (0x0), event_count (1)

Mar 3 09:36:43.491: ISDN Q921d: isdn_from_driver_process: QUEUE_EVENT

Mar 3 09:36:43.491: ISDN BR0 Q921r: User RX <-

Mar 3 09:36:43.491: 009B0101--0000009D

Mar 3 09:36:43.491: ISDN BR0 Q921d: process_rxdata: Frame sent to L2

Mar 3 09:36:43.491: ISDN BR0 PACKET: L2IF_SendPkt:

source_id (0x200), state (0x0),

dest_id (0x20A), event (0x141),

sapi (0x0), ces (0x0), call_id (0x0),

chan_id (0x0), int_id (0x87749534), free_pkt (0x1),

pkt (0x8A128250), msecs (0x0), priority (0xA)

Mar 3 09:36:43.491: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F2D33D4), size (4)

Mar 3 09:36:43.491: 009B0101--0000009D

Mar 3 09:36:43.491: ISDN BR0 PACKET: L2IF_SendPkt: Sending from L2

dest_id = 0, source_id = 512, prim = 141

priv_ln = 4, int_id = -2022402764

datastart = 1F2D33C4, netstart = 1F2D33D4, datasize = 20

Mar 3 09:36:43.491: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F2D33C4), size (20)

Mar 3 09:36:43.491: 87749534000002000141040000000600009B0101--000002AF

Mar 3 09:36:43.491: ISDN Q921d: isdn_from_driver_process: event_count 1

Mar 3 09:36:43.491: ISDN L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0

Mar 3 09:36:43.491: ISDN BR0 PACKET: handle_mail: received:

dest_id = 0, source_id = 512, prim = 141

priv_ln = 4, int_id = -2022402764, datasize = 20

Mar 3 09:36:43.491: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F2D33D4), size (20)

Mar 3 09:36:43.491: 009B010150542014E97600000000000000000000--000002D4

Mar 3 09:36:43.491: ISDN BR0 PACKET: handle_mail: received data =

Mar 3 09:36:43.491: 009B0101--0000009D

Mar 3 09:36:43.491: ISDN L2_CODE: isdn_l2_process: TIMER_EVENT

Mar 3 09:36:43.491: ISDN L2_CODE: isdn_l2_process: state (0x0), event_count (1)

Router#ping 131.

Mar 3 09:36:53.487: ISDN Q921d: isdn_from_driver_process: QUEUE_EVENT

Mar 3 09:36:53.487: ISDN BR0 Q921r: User RX <-

Mar 3 09:36:53.487: 029B0101--0000009F

Mar 3 09:36:53.487: ISDN BR0 Q921d: process_rxdata: Frame sent to L2

Mar 3 09:36:53.487: ISDN BR0 PACKET: L2IF_SendPkt:

source_id (0x200), state (0x0),

dest_id (0x20A), event (0x141),

sapi (0x0), ces (0x0), call_id (0x0),

chan_id (0x0), int_id (0x87749534), free_pkt (0x1),

pkt (0x8A12870C), msecs (0x0), priority (0xA)

Mar 3 09:36:53.487: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F2D3AB4), size (4)

Mar 3 09:36:53.487: 029B0101--0000009F

Mar 3 09:36:53.487: ISDN BR0 PACKET: L2IF_SendPkt: Sending from L2

dest_id = 0, source_id = 512, prim = 141

priv_ln = 4, int_id = -2022402764

datastart = 1F2D3AA4, netstart = 1F2D3AB4, datasize = 20

Mar 3 09:36:53.487: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F2D3AA4), size (20)

Mar 3 09:36:53.487: 87749534000002000141040000000600029B0101--000002B

Router#ping 131

Mar 3 09:36:53.487: ISDN Q921d: isdn_from_driver_process: event_count 1

Mar 3 09:36:53.487: ISDN L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0

Mar 3 09:36:53.487: ISDN BR0 PACKET: handle_mail: received:

dest_id = 0, source_id = 512, prim = 141

priv_ln = 4, int_id = -2022402764, datasize = 20

Mar 3 09:36:53.487: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1F2D3AB4), size (20)

Mar 3 09:36:53.487: 029B0101266DA049FB55E07E0000000000000000--000004C9

Mar 3 09:36:53.487: ISDN BR0 PACKET: handle_mail: received data =

Mar 3 09:36:53.487: 029B0101--0000009F

Mar 3 09:36:53.487: ISDN BR0 L2_CODE: L2IF_GetDriverID()

Mar 3 09:36:53.487: ISDN BR0 PACKET: L2IF_SendprimPkt:

source_id (0x20A), state (0x7), dest_id (0x200), event (0x140),

sapi (0x0), ces (0x1), call_id (0x0),

chan_id (0x0), int_id (0x87749534), free_p (0x1), pkt (0x877752B0), msecs (0x0), priority (0xA)

Mar 3 09:36:53.487: ISDN BR0 PACKET: L2IF_SendPkt:

source_id (0x20A), state (0x7),

dest_id (0x200), event (0x140),

sapi (0x0), ces (0x1), call_id (0x0),

chan_id (0x0), int_id (0x87749534), free_pkt (0x1),

pkt (0x86DEC768), msecs (0x0), priority (0xA)

Mar 3 09:36:53.487: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1E701B98), size (4)

Mar 3 09:36:53.487: 029B0101--0000009F

Mar 3 09:36:53.487: ISDN BR0 PACKET: L2IF_SendPkt: Sending from L2

dest_id = 0, source_id = 522, prim = 140

priv_ln = 4, int_id = -2022402764

datastart = 1E701B88, netstart = 1E701B98, datasize = 20

Mar 3 09:36:53.487: ISDN BR0 PACKET: L2IF_DispPak: packet - start(1E701B88), size (20)

Mar 3 09:36:53.487: 877495340000020A0140040000010603029B0101--000002BE

Mar 3 09:36:53.487: ISDN L2_CODE: isdn_l2_process: TIMER_EVENT

Mar 3 09:36:53.487: ISDN Q921d: isdn_l2d_srq_process: QUEUE_EVENT

Mar 3 09:36:53.487: ISDN BR0 PACKET: handle_l2d_srq_mail: received:

dest_id = 0x0000, source_id = 0x020A, prim = 0x0140

priv_ln = 4, int_id = 0x87749534, datasize = 4

line_status[0] = IF_ACTIVE

Mar 3 09:36:53.487: ISDN BR0 Q921r: User TX ->

Mar 3 09:36:53.487: 029B0101--0000009F

Mar 3 09:36:53.487: ISDN Q921d: isdn_l2d_srq_process: event_count 1

Mar 3 09:36:53.491: ISDN L2_CODE: isdn_l2_process: state (0x0), event_count (1)1.un all

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

any help ??

what could be the problem ??

may be from hostname ?

fron user & pass ?

grom dial string ??

any help ?

Dragan Ilic · ‎03-03-2014

Could you post "debug ppp negotiation" and "debug ppp authentication"?

Also I don't thin you need "ppp multilink" and "ppp authentication" under interface bri...

HTH,
Dragan

Richard Burts · ‎03-03-2014

I see in the config that Dialer1 is set to authenticate using Chap and that a hostname is supplied. But I do not see anything that supplies a password to be used in the Chap authentication.

HTH

Rick

HTH

Rick

Dr.X · ‎03-03-2014

hi ,

thans alot for you both DARGAN & RICK

actually there was a forgotten command under the dialer 1 interface

i was supposed to add pool under the interface

after i did it ,

isdn is up and every thing was fine

agian ,

im appreciating your time both for help

regards

Richard Burts · ‎03-03-2014

I am glad that you got it worked out and that our responses were helpful. Thank you for posting back to the forum to tell us that you have resolved it and what you did to solve the problem. That kind of feedback is helpful to other participants in the forum.

HTH

Rick

HTH

Rick

Dr.X · ‎03-03-2014

hi richard ,

i added :

dialer pool 1

under the dialer 1 interface

regards