[rbak-nsp] SE800 PPPoE disconnecting

Wed Mar 23 06:21:13 EDT 2011

Hi

I've got some problems with terminating PPPoE on my SE800. I have a 
context for pppoe where are subscribers. One of ethernet port is bind to 
this context. Maybe someone could help me whit this. Here is how it 
looks like:

context PPPoE
  domain adc.com
!
  no ip domain-lookup
!
  interface PPPoE
   ip address 80.94.28.1/24
!
  interface TO_c65
   ip address 10.201.0.82/30
  no logging console
!

  aaa authentication administrator local
  aaa authentication administrator maximum sessions 1
!
  subscriber default
!
  subscriber name kuki
    password 123456
    ip address 80.94.28.2/24
    ppp mtu 1492
    pppoe client route 10.201.0.81/30 10
!
!
!
end
port ethernet 3/3
  no shutdown
  encapsulation pppoe
  bind authentication chap context PPPoE maximum 2000
!

############## debug pppoe

[PPPoE]redback#Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT: I 
src_addr: 00:0c:29:33:bf:52 dst_addr: ff:ff:ff:ff:ff:ff eth_type: 0x8863 
ver_type: 17 code: PADI sess_id: 0 len: 16
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT:  01 01 00 00 01 03 
00 08 07 00 00 00 0d 00 00 00
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-DISC: [0] Received PADI: 
local mac: ff:ff:ff:ff:ff:ff remote mac: 00:0c:29:33:bf:52
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-DISC: [0] add any 
service, tlen 4
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-DISC: [0] Sent PADO
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT: O src_addr: 
00:30:88:01:10:02 dst_addr: 00:0c:29:33:bf:52 eth_type: 0x8863 ver_type: 
17 code: PADO sess_id: 0 len: 42
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT:  01 03 00 08 07 00 
00 00 0d 00 00 00 01 02 00 16 72 65 64 62 61 63 6b 2d 39 43 30 37 34 30 
33 30 33 30 30 32 33 31 01 01 00 00
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT: [0] Sending packet
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT: I src_addr: 
00:0c:29:33:bf:52 dst_addr: 00:30:88:01:10:02 eth_type: 0x8863 ver_type: 
17 code: PADR sess_id: 0 len: 16
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT:  01 01 00 00 01 03 
00 08 07 00 00 00 0e 00 00 00
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-DISC: [0] Received PADR: 
local mac: 00:30:88:01:10:02 remote mac: 00:0c:29:33:bf:52
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-CCT: [0] sess handle 
3/3:1023:63/6/2/7 added for rcct
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-CCT: [0] Inserting vcct 
3/3:1023:63/6/2/7 in vcct tree
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-CCT: [0] vcct: 
3/3:1023:63/6/2/7 encap: ethernet-pppoe-ppp mac: 00:0c:29:33:bf:52 
created, count 1 max sessions 2000
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-ISM: [7] sending vcct 
create to ISM
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-ISM: [7] sending cct 
config to ISM, encaps ethernet-pppoe-ppp
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-ISM: [7] sending cfg to 
ISM, sess-id 7, src_mac: 00:00:00:00:00:00 dst_mac: 00:0c:29:33:bf:52
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-ISM: [7] sending forced 
to PPP: 0
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-DISC: [7] Prequeued PADS
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-IPCI: Processing IPC 
event: pppoe-ppp-sync
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-IPCI: [7] Processing 
ppp-unit-ready event
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-DISC: [0] Sent PADS
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT: O src_addr: 
00:30:88:01:10:02 dst_addr: 00:0c:29:33:bf:52 eth_type: 0x8863 ver_type: 
17 code: PADS sess_id: 7 len: 42
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT:  01 01 00 00 01 03 
00 08 07 00 00 00 0e 00 00 00 01 02 00 16 72 65 64 62 61 63 6b 2d 39 43 
30 37 34 30 33 30 33 30 30 32 33 31
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT: [0] Sending packet
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-ISM: Processing ISM 
event: CCT cfg; CCT ethcfg, encap ethernet-pppoe-ppp context 0x0
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-ISM: [7] configuration 
done: session id 7 dst_mac_addr: 00:0c:29:33:bf:52
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-ISM: Processing ISM 
event: CCT cfg; CCT ethcfg, encap ethernet-pppoe-ppp context 0x0
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-ISM: [7] configuration 
done: session id 7 dst_mac_addr: 00:0c:29:33:bf:52
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-IPCI: Processing IPC 
event: pppoe-ppp-sync
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-IPCI: [7] Processing 
ppp-sess-stopping event
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT: I src_addr: 
00:0c:29:33:bf:52 dst_addr: 00:30:88:01:10:02 eth_type: 0x8863 ver_type: 
17 code: PADT sess_id: 7 len: 0
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-DISC: [0] Received PADT, 
session id 7
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-DISC_E: [7] Received 
PADT: already terminating
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-ISM: Processing ISM 
event: CCT state; CCT del; encap: ethernet-pppoe-ppp
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-ISM: [7] received circuit 
delete
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-DISC: [7] add to hdown, 
cur_idx 6 oldest_idx 0
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-PADX: [7] built PADX code 167
Mar 22 11:34:44: [3/3:1023:63/6/2/7]: %PPPOE-7-PADX: [7] Sent PADT packet
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-CCT: [0] Deleting vcct 
3/3:1023:63/6/2/7 from vcct tree
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-CCT: [0] vcct: 
3/3:1023:63/6/2/7 session id 7 deleted, rcct count 0
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT: O src_addr: 
00:30:88:01:10:02 dst_addr: 00:0c:29:33:bf:52 eth_type: 0x8863 ver_type: 
17 code: PADT sess_id: 7 len: 0
Mar 22 11:34:44: [3/3:1023:63/1/1/15]: %PPPOE-7-PKT: [0] Sending packet