#19107: "Save & Apply" PPPoE WAN interface settings terminates pppd and requires
manual "ifup wan" to reconnect
----------------------+-----------------------------------
Reporter: blamoo@… | Owner:
Type: defect | Status: new
Priority: normal | Milestone:
Component: luci | Version: Barrier Breaker 14.07
Keywords: |
----------------------+-----------------------------------
{{{
root@soler:~# logread -f | grep ppp
Thu Mar 5 23:02:40 2015 daemon.info pppd[1518]: Terminating on signal 15
Thu Mar 5 23:02:40 2015 daemon.info pppd[1518]: Connect time 0.3 minutes.
Thu Mar 5 23:02:40 2015 daemon.info pppd[1518]: Sent 61989 bytes,
received 47709 bytes.
Thu Mar 5 23:02:40 2015 daemon.notice netifd: Network device 'pppoe-wan'
link is down
Thu Mar 5 23:02:40 2015 daemon.err miniupnpd[1652]: Failed to get IP for
interface pppoe-wan
Thu Mar 5 23:02:40 2015 daemon.debug pppd[1518]: Script /lib/netifd/ppp-
down started (pid 1715)
Thu Mar 5 23:02:40 2015 daemon.debug pppd[1518]: sent [LCP TermReq id=0x2
"User request"]
Thu Mar 5 23:02:40 2015 daemon.debug pppd[1518]: Script /lib/netifd/ppp-
down finished (pid 1715), status = 0x1
Thu Mar 5 23:02:41 2015 daemon.info pppd[1741]: Plugin rp-pppoe.so
loaded.
Thu Mar 5 23:02:41 2015 daemon.info pppd[1741]: RP-PPPoE plugin version
3.8p compiled against pppd 2.4.7
Thu Mar 5 23:02:41 2015 daemon.notice pppd[1741]: pppd 2.4.7 started by
root, uid 0
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: Send PPPOE Discovery
V1T1 PADI session 0x0 length 12
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: dst ff:ff:ff:ff:ff:ff
src 54:e6:fc:f4:91:a9
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: [service-name] [host-
uniq 00 00 06 cd]
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: Recv PPPOE Discovery
V1T1 PADO session 0x0 length 28
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: dst 54:e6:fc:f4:91:a9
src 04:f9:38:8f:a7:62
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: [service-name] [host-
uniq 00 00 06 cd] [AC-name BR_BRUAC_CG2]
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: Send PPPOE Discovery
V1T1 PADR session 0x0 length 12
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: dst 04:f9:38:8f:a7:62
src 54:e6:fc:f4:91:a9
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: [service-name] [host-
uniq 00 00 06 cd]
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: Recv PPPOE Discovery
V1T1 PADT session 0x276c length 0
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]: dst 54:e6:fc:f4:91:a9
src 04:f9:38:8f:a7:62
Thu Mar 5 23:02:41 2015 daemon.debug pppd[1741]:
}}}
The last line says pppd was restarted, but nothing appears on processes
list.
After a manual "ifup wan" it connects with no problems:
{{{
Thu Mar 5 23:03:24 2015 daemon.info pppd[1889]: Plugin rp-pppoe.so
loaded.
Thu Mar 5 23:03:24 2015 daemon.info pppd[1889]: RP-PPPoE plugin version
3.8p compiled against pppd 2.4.7
Thu Mar 5 23:03:24 2015 daemon.notice pppd[1889]: pppd 2.4.7 started by
root, uid 0
Thu Mar 5 23:03:24 2015 daemon.debug pppd[1889]: Send PPPOE Discovery
V1T1 PADI session 0x0 length 12
Thu Mar 5 23:03:24 2015 daemon.debug pppd[1889]: dst ff:ff:ff:ff:ff:ff
src 54:e6:fc:f4:91:a9
Thu Mar 5 23:03:24 2015 daemon.debug pppd[1889]: [service-name] [host-
uniq 00 00 07 61]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: Send PPPOE Discovery
V1T1 PADI session 0x0 length 12
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: dst ff:ff:ff:ff:ff:ff
src 54:e6:fc:f4:91:a9
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: [service-name] [host-
uniq 00 00 07 61]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: Recv PPPOE Discovery
V1T1 PADO session 0x0 length 28
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: dst 54:e6:fc:f4:91:a9
src 04:f9:38:8f:a7:62
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: [service-name] [host-
uniq 00 00 07 61] [AC-name BR_BRUAC_CG2]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: Send PPPOE Discovery
V1T1 PADR session 0x0 length 12
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: dst 04:f9:38:8f:a7:62
src 54:e6:fc:f4:91:a9
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: [service-name] [host-
uniq 00 00 07 61]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: Recv PPPOE Discovery
V1T1 PADS session 0xd44c length 12
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: dst 54:e6:fc:f4:91:a9
src 04:f9:38:8f:a7:62
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: [service-name] [host-
uniq 00 00 07 61]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: PADS: Service-Name: ''
Thu Mar 5 23:03:29 2015 daemon.info pppd[1889]: PPP session is 54348
Thu Mar 5 23:03:29 2015 daemon.warn pppd[1889]: Connected to
04:f9:38:8f:a7:62 via interface eth1
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: using channel 3
Thu Mar 5 23:03:29 2015 daemon.info pppd[1889]: Using interface pppoe-wan
Thu Mar 5 23:03:29 2015 daemon.notice pppd[1889]: Connect: pppoe-wan <-->
eth1
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: sent [LCP ConfReq id=0x1
<mru 1492> <magic 0x83b745a0>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: rcvd [LCP ConfReq id=0x2
<mru 1492> <auth pap> <magic 0xfe65a31a>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: sent [LCP ConfAck id=0x2
<mru 1492> <auth pap> <magic 0xfe65a31a>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: rcvd [LCP ConfAck id=0x1
<mru 1492> <magic 0x83b745a0>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: sent [LCP EchoReq id=0x0
magic=0x83b745a0]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: sent [PAP AuthReq id=0x1
user="[email protected]" password=<hidden>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: rcvd [LCP EchoRep id=0x0
magic=0xfe65a31a]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: rcvd [PAP AuthAck id=0x1
"Authentication success,Welcome!"]
Thu Mar 5 23:03:29 2015 daemon.info pppd[1889]: Remote message:
Authentication success,Welcome!
Thu Mar 5 23:03:29 2015 daemon.notice pppd[1889]: PAP authentication
succeeded
Thu Mar 5 23:03:29 2015 daemon.notice pppd[1889]: peer from calling
number 04:F9:38:8F:A7:62 authorized
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: sent [IPCP ConfReq
id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: sent [IPV6CP ConfReq
id=0x1 <addr fe80::f1f9:d147:351b:a993>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: rcvd [IPCP ConfReq
id=0x1 <addr 200.100.88.202>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: sent [IPCP ConfAck
id=0x1 <addr 200.100.88.202>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: rcvd [IPCP ConfNak
id=0x1 <addr 189.111.5.70> <ms-dns1 200.204.0.10> <ms-dns2 200.204.0.138>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: sent [IPCP ConfReq
id=0x2 <addr 189.111.5.70> <ms-dns1 200.204.0.10> <ms-dns2 200.204.0.138>]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: rcvd [LCP ProtRej id=0x3
80 57]
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: Protocol-Reject for
'IPv6 Control Protocol' (0x8057) received
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: rcvd [IPCP ConfAck
id=0x2 <addr 189.111.5.70> <ms-dns1 200.204.0.10> <ms-dns2 200.204.0.138>]
Thu Mar 5 23:03:29 2015 daemon.notice pppd[1889]: local IP address
189.111.5.70
Thu Mar 5 23:03:29 2015 daemon.notice pppd[1889]: remote IP address
200.100.88.202
Thu Mar 5 23:03:29 2015 daemon.notice pppd[1889]: primary DNS address
200.204.0.10
Thu Mar 5 23:03:29 2015 daemon.notice pppd[1889]: secondary DNS address
200.204.0.138
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: Script /lib/netifd/ppp-
up started (pid 1926)
Thu Mar 5 23:03:29 2015 daemon.notice netifd: Network device 'pppoe-wan'
link is up
Thu Mar 5 23:03:29 2015 daemon.debug pppd[1889]: Script /lib/netifd/ppp-
up finished (pid 1926), status = 0x1
Thu Mar 5 23:03:30 2015 user.notice firewall: Reloading firewall due to
ifup of wan (pppoe-wan)
}}}
--
Ticket URL: <https://dev.openwrt.org/ticket/19107>
OpenWrt <http://openwrt.org>
Opensource Wireless Router Technology
_______________________________________________
openwrt-tickets mailing list
[email protected]
https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-tickets
