[rbak-nsp] SE800 PPPoE disconnecting

Wed Mar 23 06:28:44 EDT 2011

Hi Lukas, 

it seems the SmartEdge is receiving PADT ( termination ) from the client, for this reason it terminates the session. I would suggest to enable the ppp packets as well, to see if something is going on at higher protocol. 

This is your negotiation:

PPPOE PADI from client to SmartEdge:
PPPoE Tags:
   Service-Name (0101) Length: 0 Value: Any service
    Host-Uniq (0103) Length: 8 Value: 0x07 00 00 00 0d 00 00 00 

PPPOE PADO from SmartEdge to client:
PPPoE Tags:
    Host-Uniq (0103) Length: 8 Value: 0x07 00 00 00 0d 00 00 00 
    AC-Name (0102) Length: 22 Value: redback-9C074030300231
    Service-Name (0101) Length: 0 Value: Any service

PPPOE PADR from client to SmartEdge:
PPPoE Tags:
    Service-Name (0101) Length: 0 Value: Any service
    Host-Uniq (0103) Length: 8 Value: 0x07 00 00 00 0e 00 00 00 

PPPOE PADS from SmartEdge to client:
PPPoE Tags:
    Service-Name (0101) Length: 0 Value: Any service
    Host-Uniq (0103) Length: 8 Value: 0x07 00 00 00 0e 00 00 00 
    AC-Name (0102) Length: 22 Value: redback-9C074030300231

PPPOE PADT from client to SmartEdge:
PADT sess_id: 7

Thanks
Stefano

On Mar 23, 2011, at 11:21 AM, Lukasz Martyniak wrote:

> 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
> 
> 
> _______________________________________________
> redback-nsp mailing list
> redback-nsp at puck.nether.net
> https://puck.nether.net/mailman/listinfo/redback-nsp