SYSTEM WARNING: 'date_default_timezone_get(): It is not safe to rely on the system's timezone settings. You are *required* to use the date.timezone setting or the date_default_timezone_set() function. In case you used any of those methods and you are still getting this warning, you most likely misspelled the timezone identifier. We selected the timezone 'UTC' for now, but please set date.timezone to select your timezone.' in '/usr/share/mantis/www/core.php' line 264

0004359: Error re-connection with pppd (same problem with 2.5.1) - MantisBT

Endian Issue Tracker

Please see now our new Bugtracker system: JIRA

View Issue Details [ Jump to Notes ]

[ Issue History ] [ Print ]

Project

Category

View Status

Date Submitted

Last Update

0004359

Endian Firewall

Network related (VPN, uplinks)

public

2012-05-14 18:43

Reporter

xavier

Assigned To

Priority

normal

Severity

minor

Reproducibility

random

Status

new

Resolution

open

Platform

OS Version

Product Version

2.4.1

Target Version

Fixed in Version

Summary

0004359: Error re-connection with pppd (same problem with 2.5.1)

Description

generally on Week end the link is going down, and after that it is imposible to connect again via ppp to the ISP. After a while the server is still working (responding DHCP request) bt impossible to enter the amdinistration interface, i have to reboot the firewall to have it working again.
I have this problem in 2 different places with the same ISP (same time disconnect and same time firewall problem).
This problem appears once a week (more or less), hand i have to hard reboot the firewall, because is not responding to the console restart. The problem is generated by the ISP, but after resarting everything is OK. See Trace as additional information.

Additional Information

May 13 17:14:08 pppd[18496] No response to 3 echo-requests
May 13 17:14:08 pppd[18496] Serial link appears to be disconnected.
May 13 17:14:08 pppd[18496] Connect time 822.4 minutes.
May 13 17:14:08 pppd[18496] Sent 19293687 bytes, received 16532691 bytes.
May 13 17:14:09 uplink[main] Notify uplinks daemon about status change of uplink 'main'. Status id FAILED
May 13 17:14:09 uplink[main] Uplink 'main' status: 'OFFLINE'
May 13 17:14:09 uplink[main] Starting Uplink 'main'
May 13 17:14:10 pppd[18398] Plugin rp-pppoe.so loaded.
May 13 17:14:10 pppd[18398] RP-PPPoE plugin version 3.3 compiled against pppd 2.4.4
May 13 17:14:10 pppd[18398] pppd 2.4.4 started by root, uid 0
May 13 17:14:14 pppd[18496] Connection terminated.
May 13 17:14:14 pppd[18496] Modem hangup
May 13 17:14:14 pppd[18496] Exit.
May 13 17:14:45 pppd[18398] Timeout waiting for PADO packets
May 13 17:14:45 pppd[18398] Unable to complete PPPoE Discovery
May 13 17:14:45 pppd[18398] Exit.
May 13 17:14:45 kernel [109097.466117] sky2 eth0: disabling interface
May 13 17:14:45 uplink[main] Notify uplinks daemon about status change of uplink 'main'. Status id FAILED
May 13 17:14:45 uplink[main] Uplink 'main' status: 'OFFLINE'
May 13 17:14:45 uplink[main] Could not successfully call pppd
May 13 17:14:45 kernel [109098.123957] sky2 eth0: enabling interface
May 13 17:14:45 kernel [109098.124295] ADDRCONF(NETDEV_UP): eth0: link is not ready
May 13 17:14:45 ifplugd(eth0)[3149] Link beat lost.
May 13 17:14:46 uplink[main] Stopping Uplink 'main'
May 13 17:14:46 uplink[main] Notify uplinks daemon about status change of uplink 'main'. Status id FAILED
May 13 17:14:46 uplink[main] Uplink 'main' status: 'OFFLINE'
May 13 17:14:46 uplink[main] Could not shut down link 'main'
May 13 17:14:46 uplink[main] Starting Uplink 'main'
May 13 17:14:47 ifplugd(eth0)[3149] Executing '/etc/ifplugd/ifplugd.action eth0 down'.
May 13 17:14:47 ifplugd(eth0)[3149] client: Notify uplinks daemon: [ OK ]
May 13 17:14:47 ifplugd(eth0)[3149] Program executed successfully.
May 13 17:14:47 pppd[18800] Plugin rp-pppoe.so loaded.
May 13 17:14:47 pppd[18800] RP-PPPoE plugin version 3.3 compiled against pppd 2.4.4
May 13 17:14:47 pppd[18800] pppd 2.4.4 started by root, uid 0
May 13 17:14:47 kernel [109100.012806] sky2 eth0: Link is up at 100 Mbps, full duplex, flow control both
May 13 17:14:47 kernel [109100.012831] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
May 13 17:14:48 ifplugd(eth0)[3149] Link beat detected.
May 13 17:14:49 ifplugd(eth0)[3149] Executing '/etc/ifplugd/ifplugd.action eth0 up'.
May 13 17:14:49 ifplugd(eth0)[3149] client: Notify uplinks daemon: [ OK ]
May 13 17:14:49 ifplugd(eth0)[3149] Program executed successfully.
May 13 17:14:58 kernel [109110.572087] eth0: no IPv6 routers present
May 13 17:15:22 pppd[18800] Timeout waiting for PADO packets
May 13 17:15:22 pppd[18800] Unable to complete PPPoE Discovery
May 13 17:15:22 pppd[18800] Exit.
May 13 17:15:22 kernel [109134.963119] sky2 eth0: disabling interface
May 13 17:15:22 uplink[main] Notify uplinks daemon about status change of uplink 'main'. Status id FAILED
May 13 17:15:22 uplink[main] Uplink 'main' status: 'OFFLINE'
May 13 17:15:22 uplink[main] Could not successfully call pppd
May 13 17:15:23 kernel [109135.661023] sky2 eth0: enabling interface
May 13 17:15:23 kernel [109135.661408] ADDRCONF(NETDEV_UP): eth0: link is not ready
May 13 17:15:23 ifplugd(eth0)[3149] Link beat lost.
May 13 17:15:23 uplink[main] Stopping Uplink 'main'
May 13 17:15:24 uplink[main] Notify uplinks daemon about status change of uplink 'main'. Status id FAILED
May 13 17:15:24 uplink[main] Uplink 'main' status: 'OFFLINE'
May 13 17:15:24 uplink[main] Could not shut down link 'main'
May 13 17:15:24 uplink[main] Starting Uplink 'main'
May 13 17:15:24 ifplugd(eth0)[3149] Executing '/etc/ifplugd/ifplugd.action eth0 down'.
May 13 17:15:24 pppd[19560] Plugin rp-pppoe.so loaded.
May 13 17:15:24 pppd[19560] RP-PPPoE plugin version 3.3 compiled against pppd 2.4.4
May 13 17:15:24 pppd[19560] pppd 2.4.4 started by root, uid 0
May 13 17:15:25 kernel [109137.361890] sky2 eth0: Link is up at 100 Mbps, full duplex, flow control both
May 13 17:15:25 kernel [109137.362212] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
May 13 17:15:25 ifplugd(eth0)[3149] Link beat detected.
May 13 17:15:26 ifplugd(eth0)[3149] Executing '/etc/ifplugd/ifplugd.action eth0 up'.
May 13 17:15:26 ifplugd(eth0)[3149] client: Notify uplinks daemon: [ OK ]
May 13 17:15:26 ifplugd(eth0)[3149] Program executed successfully.
May 13 17:15:35 kernel [109147.583055] eth0: no IPv6 routers present
May 13 17:15:59 pppd[19560] Timeout waiting for PADO packets
May 13 17:15:59 pppd[19560] Unable to complete PPPoE Discovery
May 13 17:15:59 pppd[19560] Exit.
May 13 17:16:00 kernel [109172.399193] sky2 eth0: disabling interface
May 13 17:16:00 uplink[main] Notify uplinks daemon about status change of uplink 'main'. Status id FAILED
May 13 17:16:00 uplink[main] Uplink 'main' status: 'OFFLINE'
May 13 17:16:00 uplink[main] Could not successfully call pppd
May 13 17:16:00 kernel [109173.196929] sky2 eth0: enabling interface
May 13 17:16:00 kernel [109173.197580] ADDRCONF(NETDEV_UP): eth0: link is not ready
May 13 17:16:00 ifplugd(eth0)[3149] Link beat lost.
May 13 17:16:02 ifplugd(eth0)[3149] Executing '/etc/ifplugd/ifplugd.action eth0 down'.
May 13 17:16:02 ifplugd(eth0)[3149] client: Notify uplinks daemon: [ OK ]
May 13 17:16:02 ifplugd(eth0)[3149] Program executed successfully.
May 13 17:16:02 uplink[main] Starting Uplink 'main'
May 13 17:16:02 kernel [109174.911201] sky2 eth0: Link is up at 100 Mbps, full duplex, flow control both
May 13 17:16:02 kernel [109174.913383] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
May 13 17:16:03 ifplugd(eth0)[3149] Link beat detected.
May 13 17:16:03 pppd[19925] Plugin rp-pppoe.so loaded.
May 13 17:16:03 pppd[19925] RP-PPPoE plugin version 3.3 compiled against pppd 2.4.4
May 13 17:16:03 pppd[19925] pppd 2.4.4 started by root, uid 0
May 13 17:16:04 ifplugd(eth0)[3149] Executing '/etc/ifplugd/ifplugd.action eth0 up'.
May 13 17:16:04 ifplugd(eth0)[3149] client: Notify uplinks daemon: [ OK ]
May 13 17:16:04 ifplugd(eth0)[3149] Program executed successfully.
May 13 17:16:05 kernel [109177.487275] ip_tables: mac match: used from hooks POSTROUTING, but only valid from PREROUTING/INPUT/FORWARD
May 13 17:16:05 kernel [109177.498570] ip_tables: mac match: used from hooks POSTROUTING, but only valid from PREROUTING/INPUT/FORWARD
May 13 17:16:05 kernel [109177.514493] ip_tables: mac match: used from hooks POSTROUTING, but only valid from PREROUTING/INPUT/FORWARD
May 13 17:16:05 kernel [109177.525435] ip_tables: mac match: used from hooks POSTROUTING, but only valid from PREROUTING/INPUT/FORWARD
May 13 17:16:05 kernel [109177.676295] ip_tables: mac match: used from hooks POSTROUTING, but only valid from PREROUTING/INPUT/FORWARD
May 13 17:16:05 kernel [109177.681691] ip_tables: mac match: used from hooks POSTROUTING, but only valid from PREROUTING/INPUT/FORWARD
May 13 17:16:13 kernel [109185.731057] eth0: no IPv6 routers present
May 13 17:16:15 pppd[19925] PPP session is 161
May 13 17:16:15 pppd[19925] Using interface ppp0
May 13 17:16:15 pppd[19925] Connect: ppp0 <--> eth0
May 13 17:16:17 pppd[19925] PAP authentication succeeded
May 13 17:16:17 pppd[19925] peer from calling number 00:0X:4X:8X:CX:8X authorized
May 13 17:16:17 pppd[19925] local IP address XXX.XXX.XXX.XXX
May 13 17:16:17 pppd[19925] remote IP address 10.222.114.96
May 13 17:16:17 pppd[19925] primary DNS address 66.119.93.10
May 13 17:16:17 pppd[19925] secondary DNS address 66.119.95.4
May 13 17:16:18 pppd[20262] LCP terminated by peer
May 13 17:16:18 pppd[20262] Connect time 0.1 minutes.
May 13 17:16:18 pppd[20262] Sent 0 bytes, received 0 bytes.
May 13 17:16:20 uplink[main] Notify pppcall about status change of uplink 'main'. Send OK
May 13 17:16:20 uplink[main] Notify uplinks daemon about status change of uplink 'main'. Status id OK
May 13 17:16:20 uplink[main] Uplink 'main' status: 'ONLINE'
May 13 17:16:21 uplink[main] Notify uplinks daemon about status change of uplink 'main'. Status id FAILED
May 13 17:16:21 uplink[main] Uplink 'main' status: 'OFFLINE'
May 13 17:16:21 pppd[20262] Connection terminated.
May 13 17:16:21 pppd[20262] Modem hangup
May 13 17:16:21 pppd[20262] Exit.
May 13 17:16:22 uplink[main] Stopping Uplink 'main'
May 13 17:16:22 uplink[main] Notify uplinks daemon about status change of uplink 'main'. Status id FAILED
May 13 17:16:22 uplink[main] Uplink 'main' status: 'OFFLINE'
May 13 17:16:22 uplink[main] Could not shut down link 'main'
May 13 17:16:22 uplink[main] Starting Uplink 'main'
May 13 17:16:23 pppd[20853] Plugin rp-pppoe.so loaded.
May 13 17:16:23 pppd[20853] RP-PPPoE plugin version 3.3 compiled against pppd 2.4.4
May 13 17:16:23 pppd[20853] pppd 2.4.4 started by root, uid 0
May 13 17:16:23 pppd[20853] PPP session is 860
May 13 17:16:23 pppd[20853] Using interface ppp0
May 13 17:16:23 pppd[20853] Connect: ppp0 <--> eth0
May 13 17:16:23 pppd[20853] PAP authentication succeeded
May 13 17:16:23 pppd[20853] peer from calling number 00:0X:4X:8X:CX:8X authorized
May 13 17:16:23 pppd[20853] local IP address XX.XXX.XXX.XXX
May 13 17:16:23 pppd[20853] remote IP address 10.222.114.96
May 13 17:16:23 pppd[20853] primary DNS address 66.119.93.10
May 13 17:16:23 pppd[20853] secondary DNS address 66.119.95.4
May 13 17:16:23 pppd[20856] LCP terminated by peer
May 13 17:16:23 pppd[20856] Connect time 0.0 minutes.
May 13 17:16:23 pppd[20856] Sent 0 bytes, received 0 bytes.
May 13 17:16:23 uplink[main] Notify pppcall about status change of uplink 'main'. Send FAILED
May 13 17:16:23 uplink[main] Notify uplinks daemon about status change of uplink 'main'. Status id FAILED
May 13 17:16:23 uplink[main] Uplink 'main' status: 'OFFLINE'
May 13 17:16:23 uplink[main] Could not set up routing
May 13 17:16:23 pppd[20856] Terminating on signal 15
May 13 17:16:23 pppd[20856] Terminating on signal 15
May 13 17:16:26 pppd[20856] Connection terminated.
May 13 17:21:25 uplink[main] Stopping Uplink 'main'

Issue History
Date Modified	Username	Field	Change
2012-05-14 18:43	xavier	New Issue

Relationships

Notes
There are no notes attached to this issue.