All of a sudden having issues with PPPOE connection to my UK ISP. Been working fine for years then last Friday after a power cycle on the equipment it just will not connect. Have raised support ticket with provider and verified no service/account issues. Have checked the router logs and can see that the session authenticates and connects but then immediately disconnects. Have exported a debug log of the PPPOE session which I will paste below as a lot is beyond my understanding so hopefully someone more knowledgeable can shed even a little light or suggested next steps.
Topology wise its a Draytek Vigor 130 modem that is in front of the hEX 750Gr3 router, running ROS 6.49.6. Solid light on the modem so in sync.
As a test I bought a cheap TP-Link combined modem/router and configured. Connected straight away no issues. Which was annoying.
Anyways, hope enough info for assistance.
Thanks as always,
Nell
Log Output:
Code: Select all
11:25:26 pppoe,ppp,info pppoe-out1: initializing...
11:25:26 pppoe,ppp,info pppoe-out1: connecting...
11:25:26 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
11:25:26 pppoe,debug,packet session-id=0x0000
11:25:26 pppoe,debug,packet host-uniq=0x25004500
11:25:26 pppoe,debug,packet service-name=
11:25:26 pppoe,debug,packet ppp-max-payload=1500
11:25:27 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
11:25:27 pppoe,debug,packet session-id=0x0000
11:25:27 pppoe,debug,packet host-uniq=0x25004500
11:25:27 pppoe,debug,packet service-name=
11:25:27 pppoe,debug,packet ppp-max-payload=1500
11:25:28 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
11:25:28 pppoe,debug,packet session-id=0x0000
11:25:28 pppoe,debug,packet host-uniq=0x25004500
11:25:28 pppoe,debug,packet service-name=
11:25:28 pppoe,debug,packet ppp-max-payload=1500
11:25:29 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
11:25:29 pppoe,debug,packet session-id=0x0000
11:25:29 pppoe,debug,packet host-uniq=0x25004500
11:25:29 pppoe,debug,packet service-name=
11:25:29 pppoe,debug,packet ppp-max-payload=1500
11:25:30 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
11:25:30 pppoe,debug,packet session-id=0x0000
11:25:30 pppoe,debug,packet host-uniq=0x25004500
11:25:30 pppoe,debug,packet service-name=
11:25:30 pppoe,debug,packet ppp-max-payload=1500
11:25:31 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
11:25:31 pppoe,debug,packet session-id=0x0000
11:25:31 pppoe,debug,packet host-uniq=0x25004500
11:25:31 pppoe,debug,packet service-name=
11:25:31 pppoe,debug,packet ppp-max-payload=1500
11:25:32 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
11:25:32 pppoe,debug,packet session-id=0x0000
11:25:32 pppoe,debug,packet host-uniq=0x25004500
11:25:32 pppoe,debug,packet service-name=
11:25:32 pppoe,debug,packet ppp-max-payload=1500
11:25:33 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
11:25:33 pppoe,debug,packet session-id=0x0000
11:25:33 pppoe,debug,packet host-uniq=0x25004500
11:25:33 pppoe,debug,packet service-name=
11:25:33 pppoe,debug,packet ppp-max-payload=1500
11:25:34 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
11:25:34 pppoe,debug,packet session-id=0x0000
11:25:34 pppoe,debug,packet host-uniq=0x25004500
11:25:34 pppoe,debug,packet service-name=
11:25:34 pppoe,debug,packet ppp-max-payload=1500
11:25:34 pppoe,debug,packet ether1: rcvd PADO from 14:7B:AC:B4:55:27
11:25:34 pppoe,debug,packet session-id=0x0000
11:25:34 pppoe,debug,packet service-name=
11:25:34 pppoe,debug,packet ac-name=################
11:25:34 pppoe,debug,packet host-uniq=0x25004500
11:25:34 pppoe,debug,packet ac-cookie=9b 9a 21 39 4f d8 68 c1 e1 6f 62 e4 48 12 25 80
11:25:34 pppoe,debug,packet ppp-max-payload=1500
11:25:34 pppoe,debug,packet ether1: sent PADR to 14:7B:AC:B4:55:27
11:25:34 pppoe,debug,packet session-id=0x0000
11:25:34 pppoe,debug,packet host-uniq=0x26004500
11:25:34 pppoe,debug,packet service-name=
11:25:34 pppoe,debug,packet ppp-max-payload=1500
11:25:34 pppoe,debug,packet ac-cookie=9b 9a 21 39 4f d8 68 c1 e1 6f 62 e4 48 12 25 80
11:25:34 pppoe,debug,packet ether1: rcvd PADS from 14:7B:AC:B4:55:27
11:25:34 pppoe,debug,packet session-id=0x06b2
11:25:34 pppoe,debug,packet service-name=
11:25:34 pppoe,debug,packet host-uniq=0x26004500
11:25:34 pppoe,debug,packet ppp-max-payload=1500
11:25:34 pppoe,ppp,debug pppoe-out1: LCP lowerup
11:25:34 pppoe,ppp,debug pppoe-out1: LCP open
11:25:34 pppoe,ppp,debug pppoe-out1: LCP timer
11:25:34 pppoe,ppp,debug,packet pppoe-out1: rcvd pppoe-out1: rcvd pppoe-out1: sent pppoe-out1: rcvd pppoe-out1: sent pppoe-out1: sent pppoe-out1: rcvd pppoe-out1: sent pppoe-out1: sent pppoe-out1: sent pppoe-out1: rcvd pppoe-out1: sent pppoe-out1: sent pppoe-out1: sent pppoe-out1: rcvd pppoe-out1: sent pppoe-out1: rcvd pppoe-out1: sent pppoe-out1: sent pppoe-out1: rcvd pppoe-out1: sent pppoe-out1: sent pppoe-out1: sent pppoe-out1: rcvd pppoe-out1: sent pppoe-out1: rcvd pppoe-out1: rcvd pppoe-out1: sent pppoe-out1: sent pppoe-out1: sent LCP ConfReq id=0x1f
11:25:34 pppoe,ppp,debug,packet <mru 1480>
11:25:34 pppoe,ppp,debug,packet <magic 0x3c8d29c5>
11:25:34 pppoe,ppp,debug,packet pppoe-out1: rcvd LCP ConfReq id=0xec
11:25:34 pppoe,ppp,debug,packet <mru 1500>
11:25:34 pppoe,ppp,debug,packet <magic 0x63a29734>
11:25:34 pppoe,ppp,debug,packet <auth chap>
11:25:34 pppoe,ppp,debug,packet pppoe-out1: sent LCP ConfAck id=0xec
11:25:34 pppoe,ppp,debug,packet <mru 1500>
11:25:34 pppoe,ppp,debug,packet <magic 0x63a29734>
11:25:34 pppoe,ppp,debug,packet <auth chap>
11:25:34 pppoe,ppp,debug,packet pppoe-out1: rcvd LCP ConfAck id=0x1f
11:25:34 pppoe,ppp,debug,packet <mru 1480>
11:25:34 pppoe,ppp,debug,packet <magic 0x3c8d29c5>
11:25:34 pppoe,ppp,debug pppoe-out1: LCP opened
11:25:34 pppoe,ppp,debug,packet pppoe-out1: rcvd CHAP Challenge id=0x1
11:25:34 pppoe,ppp,debug,packet <challenge len=63>
11:25:34 pppoe,ppp,debug,packet <name ################>
11:25:34 pppoe,ppp,debug,packet pppoe-out1: sent CHAP Response id=0x1
11:25:34 pppoe,ppp,debug,packet <response len=16>
11:25:34 pppoe,ppp,debug,packet <name ################>
11:25:34 pppoe,ppp,debug,packet pppoe-out1: rcvd CHAP Success id=0x1
11:25:34 pppoe,ppp,info pppoe-out1: authenticated
11:25:34 pppoe,ppp,debug pppoe-out1: IPCP lowerup
11:25:34 pppoe,ppp,debug pppoe-out1: IPCP open
11:25:34 pppoe,ppp,debug,packet pppoe-out1: sent IPCP ConfReq id=0x1c
11:25:34 pppoe,ppp,debug,packet <addr 0.0.0.0>
11:25:34 pppoe,ppp,debug pppoe-out1: IPV6CP open
11:25:34 pppoe,ppp,debug pppoe-out1: MPLSCP lowerup
11:25:34 pppoe,ppp,debug pppoe-out1: MPLSCP open
11:25:34 pppoe,ppp,debug,packet pppoe-out1: sent MPLSCP ConfReq id=0xf
11:25:34 pppoe,ppp,debug pppoe-out1: BCP open
11:25:34 pppoe,ppp,debug pppoe-out1: CCP lowerup
11:25:34 pppoe,ppp,debug pppoe-out1: CCP open
11:25:34 pppoe,ppp,debug,packet pppoe-out1: sent LCP EchoReq id=0x0
11:25:34 pppoe,ppp,debug,packet <magic 0x3c8d29c5>
11:25:34 pppoe,ppp,debug,packet <data len=1492>
11:25:34 pppoe,ppp,debug,packet pppoe-out1: rcvd IPCP ConfReq id=0x1
11:25:34 pppoe,ppp,debug,packet <addr ################>
11:25:34 pppoe,ppp,debug,packet pppoe-out1: sent IPCP ConfAck id=0x1
11:25:34 pppoe,ppp,debug,packet <addr ################>
11:25:34 pppoe,ppp,debug,packet pppoe-out1: rcvd IPV6CP ConfReq id=0x1
11:25:34 pppoe,ppp,debug,packet <interface-identifier 86b8:2ff:fe02:ed00>
11:25:34 pppoe,ppp,debug pppoe-out1: received unsupported protocol 0x8057
11:25:34 pppoe,ppp,debug,packet pppoe-out1: sent LCP ProtRej id=0x20
11:25:34 pppoe,ppp,debug,packet 80 57 01 01 00 0e 01 0a 86 b8 02 ff fe 02 ed 00
11:25:34 pppoe,ppp,debug,packet pppoe-out1: rcvd LCP ProtRej id=0x1
11:25:34 pppoe,ppp,debug,packet 82 81 01 0f 00 04
11:25:34 pppoe,ppp,debug,packet pppoe-out1: rcvd IPCP ConfNak id=0x1c
11:25:34 pppoe,ppp,debug,packet <addr ################>
11:25:34 pppoe,ppp,debug,packet pppoe-out1: sent IPCP ConfReq id=0x1d
11:25:34 pppoe,ppp,debug,packet <addr ################>
11:25:34 pppoe,ppp,debug pppoe-out1: LCP lowerdown
11:25:34 pppoe,ppp,debug pppoe-out1: LCP closed
11:25:34 pppoe,ppp,debug pppoe-out1: CCP lowerdown
11:25:34 pppoe,ppp,debug pppoe-out1: BCP lowerdown
11:25:34 pppoe,ppp,debug pppoe-out1: BCP down event in starting state
11:25:34 pppoe,ppp,debug pppoe-out1: IPCP lowerdown
11:25:34 pppoe,ppp,debug pppoe-out1: IPV6CP lowerdown
11:25:34 pppoe,ppp,debug pppoe-out1: IPV6CP down event in starting state
11:25:34 pppoe,ppp,debug pppoe-out1: MPLSCP lowerdown
11:25:34 pppoe,ppp,debug pppoe-out1: CCP close
11:25:34 pppoe,ppp,debug pppoe-out1: BCP close
11:25:34 pppoe,ppp,debug pppoe-out1: IPCP close
11:25:34 pppoe,ppp,debug pppoe-out1: IPV6CP close
11:25:34 pppoe,ppp,debug pppoe-out1: MPLSCP close
11:25:34 pppoe,ppp,info pppoe-out1: terminating... - hungup
11:25:34 pppoe,debug,packet ether1: sent PADT to 14:7B:AC:B4:55:27
11:25:34 pppoe,debug,packet session-id=0x06b2
11:25:34 pppoe,ppp,debug pppoe-out1: LCP lowerdown
11:25:34 pppoe,ppp,debug pppoe-out1: LCP down event in starting state
11:25:34 pppoe,ppp,info pppoe-out1: disconnected