Thread Info
  • State Not Answered
  • Replies 4 replies
  • Subscribers 0 subscribers
  • Views 1219 views
  • Users 0 members are here
Options
Suggested

[6.808] Daily reconnect of PPPOE interface faulty (BUG?) - fixed

KoC
I found 2 issues with the daily reconnect function of the PPPOE Interface which may be a bug:

1) if you change the Value off "Daily Reconnect" from Never to a time like 03:00 and save this option, you cannot switch it back to Never

2) if the daily reconnect is enabled, the pppoe link is brought down at the excected time, but the interface doesn't come up again. Dashboard shows it down until you push reconnect

Here my ppoe.log from today:

2006:10:27-03:00:03 (none) pppoe[3876]: Received signal 1 on session 319.
2006:10:27-03:00:03 (none) pppoe[3876]: Sent PADT
2006:10:27-03:00:03 (none) pppd-pppoe[3870]: Hangup (SIGHUP)
2006:10:27-03:00:03 (none) pppd-pppoe[3870]: Modem hangup
2006:10:27-03:00:03 (none) pppd-pppoe[3870]: Connect time 1099.1 minutes.
2006:10:27-03:00:03 (none) pppd-pppoe[3870]: Sent 24872956 bytes, received 81208063 bytes.
2006:10:27-03:00:03 (none) pppd-pppoe[3870]: Script /etc/ppp/ip-down started (pid 30761)
2006:10:27-03:00:03 (none) pppd-pppoe[3870]: Connection terminated.
2006:10:27-03:00:03 (none) pppd-pppoe[3870]: Script /usr/sbin/pppoe -I eth3 -m 1452 finished (pid 3876), status = 0x0
2006:10:27-03:00:03 (none) pppd-pppoe[3870]: Waiting for 1 child processes...
2006:10:27-03:00:03 (none) pppd-pppoe[3870]:   script /etc/ppp/ip-down, pid 30761
2006:10:27-03:00:04 (none) pppd-pppoe[3870]: Script /etc/ppp/ip-down finished (pid 30761), status = 0x0
2006:10:27-03:00:04 (none) pppd-pppoe[3870]: Exit.
2006:10:27-03:00:10 (none) pppoe-sh: Access-Concentrator: xxxxxxxx Got a cookie: 55 59 ed ef 96 38 93 b1 8f fe 5a d5 51 80 88 d4 AC-Ethernet-Address: 00:90:1a:a0:57:2f --------------------------------------------------
2006:10:27-03:00:10 (none) pppd-pppoe[30790]: pppd 2.4.3 started by root, uid 0
2006:10:27-03:00:10 (none) pppd-pppoe[30790]: using channel 2
2006:10:27-03:00:10 (none) pppd-pppoe[30790]: Using interface ppp0
2006:10:27-03:00:10 (none) pppd-pppoe[30790]: Connect: ppp0  /dev/ttyp0
2006:10:27-03:00:10 (none) pppoe[30791]: PADS: Service-Name: ''
2006:10:27-03:00:10 (none) pppoe[30791]: PPP session is 1837 (0x72d)
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: sent [LCP ConfReq id=0x1     ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: rcvd [LCP ConfReq id=0xe0   ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: sent [LCP ConfAck id=0xe0   ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: rcvd [LCP ConfAck id=0x1     ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: sent [LCP EchoReq id=0x0 magic=0x872f0e9e]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: sent [PAP AuthReq id=0x1 user="removed" password=]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: rcvd [LCP EchoRep id=0x0 magic=0x26d13973]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: rcvd [PAP AuthAck id=0x1 ""]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: PAP authentication succeeded
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: sent [CCP ConfReq id=0x1    ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: sent [IPCP ConfReq id=0x1    ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: rcvd [LCP ProtRej id=0xe1 80 fd 01 01 00 15 12 06 00 00 00 01 1a 04 78 00 18 04 78 00 15 03 2f]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: rcvd [IPCP ConfRej id=0x1 ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: sent [IPCP ConfReq id=0x2   ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: rcvd [IPCP ConfReq id=0xd3 ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: sent [IPCP ConfAck id=0xd3 ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: rcvd [IPCP ConfNak id=0x2   ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: sent [IPCP ConfReq id=0x3   ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: rcvd [IPCP ConfAck id=0x3   ]
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: local  IP address xx.xx.xx.xx (!!ip removed by me!!)
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: remote IP address 217.0.116.129
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: primary   DNS address 217.237.151.225
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: secondary DNS address 217.237.150.205
2006:10:27-03:00:11 (none) pppd-pppoe[30790]: Script /etc/ppp/ip-up started (pid 30869)
2006:10:27-03:00:14 (none) pppd-pppoe[30790]: Terminating on signal 15
2006:10:27-03:00:14 (none) pppd-pppoe[30790]: Connect time 0.1 minutes.
2006:10:27-03:00:14 (none) pppd-pppoe[30790]: Sent 73 bytes, received 0 bytes.
2006:10:27-03:00:14 (none) pppd-pppoe[30790]: sent [LCP TermReq id=0x2 "User request"]
2006:10:27-03:00:14 (none) pppd-pppoe[30790]: Script /etc/ppp/ip-up finished (pid 30869), status = 0x0
2006:10:27-03:00:14 (none) pppd-pppoe[30790]: Script /etc/ppp/ip-down started (pid 30926)
2006:10:27-03:00:17 (none) pppd-pppoe[30790]: sent [LCP TermReq id=0x3 "User request"]
2006:10:27-03:00:17 (none) pppd-pppoe[30790]: Script /etc/ppp/ip-down finished (pid 30926), status = 0x0
2006:10:27-03:00:18 (none) pppd-pppoe[30790]: Modem hangup
2006:10:27-03:00:18 (none) pppd-pppoe[30790]: Connection terminated.
2006:10:27-03:00:18 (none) pppd-pppoe[30790]: Waiting for 1 child processes...
2006:10:27-03:00:18 (none) pppd-pppoe[30790]:   script /usr/sbin/pppoe -I eth3 -m 1452, pid 30791
2006:10:27-03:00:18 (none) pppd-pppoe[30790]: Child process /usr/sbin/pppoe -I eth3 -m 1452 (pid 30791) terminated with signal 15
2006:10:27-03:00:18 (none) pppd-pppoe[30790]: Exit.

I waited until 19 o'clock :-) but the interface coul only be restarted manually.
  • 0
    I found the next bug.

    To recover my system to automatically reconnect to the internet, i delted the PPPOE Interface and added it manually again. Now the reconnect works when my DSL-Provider disconnects my after 24 hours. 

    BUT:

    Deleting the PPPOE Interface broke up my dnynds configuration. When i tried to reenter the configuratio i alwas get the error : One of the values you entered is syntically or logically incorrect.
    It is impossible to get dyndns working again without reinstalling the whole system!
  • 0
    Hi,

    the reconnection-error still exists in 6.819. After automatic reconnect at i.e. 5:00 o'clock, the PPPOE doesn't work anymore, a reconnect via webinterface is ineffective. I had to reboot ASG to solve this problem.
  • 0 in reply to Suppentrulli
    Hi,
    sometimes the reconnect still doesn't work with 6.836, i have to disable/enable the pppoe-interface.


    2006:11:27-05:00:01 (none) pppd-pppoe[12156]: Hangup (SIGHUP)
    2006:11:27-05:00:01 (none) pppd-pppoe[12156]: Connect time 1439.6 minutes.
    2006:11:27-05:00:01 (none) pppd-pppoe[12156]: Sent 14187891 bytes, received 227993838 bytes.
    2006:11:27-05:00:01 (none) pppoe[12157]: Received signal 1 on session 7012.
    2006:11:27-05:00:01 (none) pppoe[12157]: Sent PADT
    2006:11:27-05:00:01 (none) pppd-pppoe[12156]: Script /etc/ppp/ip-down started (pid 4791)
    2006:11:27-05:00:01 (none) pppd-pppoe[12156]: sent [LCP TermReq id=0x2 "User request"]
    2006:11:27-05:00:01 (none) pppd-pppoe[12156]: Modem hangup
    2006:11:27-05:00:01 (none) pppd-pppoe[12156]: Connection terminated.
    2006:11:27-05:00:01 (none) pppd-pppoe[12156]: Script /usr/sbin/pppoe -I eth2 -m 1452 finished (pid 12157), status = 0x0
    2006:11:27-05:00:01 (none) pppd-pppoe[12156]: Waiting for 1 child processes...
    2006:11:27-05:00:01 (none) pppd-pppoe[12156]:   script /etc/ppp/ip-down, pid 4791
    2006:11:27-05:00:03 (none) pppd-pppoe[12156]: Script /etc/ppp/ip-down finished (pid 4791), status = 0x0
    2006:11:27-05:00:03 (none) pppd-pppoe[12156]: Exit.
    2006:11:27-05:00:11 (none) pppoe-sh: Access-Concentrator: XXXXXX-XXX Got a cookie: 14 a5 34 fb 02 9f 32 70 e6 cc 39 d5 1e 34 73 d6 AC-Ethernet-Address: 00:90:1a:a0:3d:4d --------------------------------------------------
    2006:11:27-05:00:11 (none) pppd-pppoe[4869]: pppd 2.4.3 started by root, uid 0
    2006:11:27-05:00:11 (none) pppd-pppoe[4869]: using channel 4
    2006:11:27-05:00:11 (none) pppd-pppoe[4869]: Using interface ppp0
    2006:11:27-05:00:11 (none) pppd-pppoe[4869]: Connect: ppp0  /dev/ttyp0
    2006:11:27-05:00:11 (none) pppoe[4870]: PADS: Service-Name: ''
    2006:11:27-05:00:11 (none) pppoe[4870]: PPP session is 4600 (0x11f8)
    2006:11:27-05:00:11 (none) pppd-pppoe[4869]: Terminating on signal 15
    2006:11:27-05:00:11 (none) pppd-pppoe[4869]: Modem hangup
    2006:11:27-05:00:11 (none) pppd-pppoe[4869]: Connection terminated.
    2006:11:27-05:00:11 (none) pppd-pppoe[4869]: Child process /usr/sbin/pppoe -I eth2 -m 1452 (pid 4870) terminated with signal 15
    2006:11:27-05:00:11 (none) pppd-pppoe[4869]: Exit.