Ethernet fails on IPCop / Linux

[Unchained]

Have sent pm / mail via forum

Find something like (in rc.red, if you really want to change it :

my @pppoecommand = ('/usr/sbin/pppoe', '-p','/var/run/pppoe.pid','-I',
                       $netsettings{'RED_DEV'}, '-T', '80', '-U', '-m', '[B]1412[/B]');

and change it to:

my @pppoecommand = ('/usr/sbin/pppoe', '-p','/var/run/pppoe.pid','-I',
                        $netsettings{'RED_DEV'}, '-T', '80', '-U', '-m', '[B][COLOR="Red"]1272[/COLOR][/B]');

Seems to work for me...

 

[8321]

Hi, made the change. Still not connecting. Log still reports timeout waiting for PADO- but mtu value in the log is now 1272.

I haven't got a PM yet.

Johan from WBS helpdesk called in response to a call I logged yesterday. He says mtu cannot be set in IPCop as far as he knows. He agreed with me that this meant that IPCop cannot be used on the iBust network and that nobody should be able to connect with it. I believe there are users connecting successfully - like unchained.

 

[Unchained]

Have resent PM (previous was email), but can't remember exactly what I said...

Have a look at www.unchained.co.za for all kinds of info, also instructions by Seburn relating to IPCop MTU changes. The basic changes to rc.red show the correct values on System | Network Status after the interface has been restarted, eg disconnect / reconnect.

Never before the recent problems have I had to change the MTU to get connected...

BTW, I'm on Wonderboom, apparently interference between Wonderboom, Sunnypark & Menlo Park might have influenced the connectivity from these towers. I also noticed more "ghost" towers with utTraceStar since Menlo Park went live, which might indicate interference?

 

[Unchained]

Wonderboom down again for IPCop / Linux

The problem of not being able to connect from Linux (FC4) and IPCop is back again for Wonderboom since about 22:20 this evening... Just when I thought it was safe to use iBurst again. BTW it fails with the rp-pppoe plugin and kernel support pppoe in IPCop, both worked before the time, then I just got disconnected at about 22:20 (trying to refresh this forum).

Recent RED i/f log:
22:28:37 red: Connectioncheck RED fail to connect
22:28:35 pppd[17954] Exit.
22:28:35 pppd[17954] Connection terminated.
22:28:35 pppd[17954] Modem hangup
22:28:35 pppd[17954] Script /usr/sbin/pppoe -p /var/run/pppoe.pid -I eth1 -T 80 -U -m 1272 -S iBurst finished (pid 17956), status = 0x0
22:28:35 pppoe[17956] Sent PADT
22:28:35 pppoe[17956] Session 883 terminated -- received PADT from peer
22:28:26 pppd[17954] sent [LCP ConfReq id=0x1 <mru 1352> <magic 0x516dd8d3>]
22:28:25 pppd[17954] Discarded non-LCP packet when LCP not open
22:28:25 pppd[17954] rcvd [proto=0xff] 03 c0 21 05 0b 00 04
22:28:23 pppd[17954] Discarded non-LCP packet when LCP not open
22:28:23 pppd[17954] rcvd [proto=0xff] 03 c0 21 02 01 00 0e 01 04 05 48 05 06 51 6d d8 d3
...
...
22:27:56 pppd[17954] Discarded non-LCP packet when LCP not open
22:27:56 pppd[17954] rcvd [proto=0xff] 03 c0 21 02 01 00 0e 01 04 05 48 05 06 51 6d d8 d3
22:27:56 pppd[17954] sent [LCP ConfReq id=0x1 <mru 1352> <magic 0x516dd8d3>]
22:27:55 pppd[17954] Discarded non-LCP packet when LCP not open
22:27:55 pppd[17954] rcvd [proto=0xff] 03 c0 21 01 01 00 12 01 04 05 98 03 04 c0 23 05 06 84 fa 61 49
22:27:55 pppoe[17956] PPP session is 883
22:27:55 pppoe[17956] PADS: Service-Name: 'iBurst'
22:27:55 pppd[17954] Connect: ppp0 <--> /dev/pts/1
22:27:55 pppd[17954] Using interface ppp0
22:27:55 pppd[17954] using channel 5
22:27:55 pppd[17954] pppd 2.4.2 started by root, uid 0
22:27:53 red: Connectioncheck Restarting 4/5
22:27:20 red: Connectioncheck RED fail to connect
22:27:18 pppd[17654] Exit.
22:27:18 pppd[17654] Connection terminated.
22:27:18 pppd[17654] Modem hangup
22:27:18 pppd[17654] Script /usr/sbin/pppoe -p /var/run/pppoe.pid -I eth1 -T 80 -U -m 1272 -S iBurst finished (pid 17656), status = 0x0
22:27:18 pppoe[17656] Sent PADT
22:27:18 pppoe[17656] Session 882 terminated -- received PADT from peer
22:27:09 pppd[17654] sent [LCP ConfReq id=0x1 <mru 1352> <magic 0x60b53070>]
22:27:08 pppd[17654] Discarded non-LCP packet when LCP not open
22:27:08 pppd[17654] rcvd [proto=0xff] 03 c0 21 05 0b 00 04

 

[Unchained]

... and fixed itself by 11:25 ...

 

[8321]

Menlo Park 7am

Last night iBust/IPCop were flying.

Tried to connect this morning and had to Connect/Disconnect half a dozen times before connection was magically established. I didn't change anything. Here is part of the log. Please refrain from mentioning mtus. Can we see some more logs to pick up a pattern?

>>> sent connect message
Nov 24 07:24:20 p219 ipcop: Starting RED device eth1.
Nov 24 07:24:20 p219 ipcop: Dialling iBurst.
Nov 24 07:24:20 p219 pppd[10519]: Plugin rp-pppoe.so loaded.
Nov 24 07:24:20 p219 pppd[10519]: RP-PPPoE plugin version 3.3 compiled against pppd 2.4.2
Nov 24 07:24:20 p219 pppd[10520]: pppd 2.4.2 started by root, uid 0
Nov 24 07:24:21 p219 pppd[10520]: PADS: Service-Name: ''
Nov 24 07:24:21 p219 pppd[10520]: PPP session is 22
Nov 24 07:24:21 p219 pppd[10520]: using channel 26
Nov 24 07:24:21 p219 pppd[10520]: Using interface ppp0
Nov 24 07:24:21 p219 pppd[10520]: Connect: ppp0 <--> eth1
Nov 24 07:24:21 p219 pppd[10520]: Couldn't increase MTU to 1500
Nov 24 07:24:21 p219 pppd[10520]: Couldn't increase MRU to 1500
Nov 24 07:24:21 p219 pppd[10520]: sent [LCP ConfReq id=0x1 <magic 0x994b47b7>]
Nov 24 07:24:54 p219 last message repeated 11 times
>>> sent Disconnect message
Nov 24 07:24:57 p219 pppd[10520]: sent [LCP ConfReq id=0x1 <magic 0x994b47b7>]
Nov 24 07:25:00 p219 fcron[10651]: Job /usr/local/bin/timecheck > /dev/null 2>&1 started for user root (pid 10654)
Nov 24 07:25:00 p219 fcron[10652]: Job /usr/local/bin/makegraphs >/dev/null started for user root (pid 10655)
Nov 24 07:25:00 p219 fcron[10653]: Job [ -f "/var/ipcop/red/active" ] && /usr/local/bin/setddns.pl started for user root (pid 10657)
Nov 24 07:25:00 p219 fcron[10653]: Job [ -f "/var/ipcop/red/active" ] && /usr/local/bin/setddns.pl terminated (exit status: 1)
Nov 24 07:25:00 p219 pppd[10520]: sent [LCP ConfReq id=0x1 <magic 0x994b47b7>]
Nov 24 07:25:03 p219 pppd[10520]: Terminating on signal 15.
Nov 24 07:25:03 p219 pppd[10520]: sent [LCP TermReq id=0x2 "User request"]
Nov 24 07:25:06 p219 pppd[10520]: sent [LCP TermReq id=0x3 "User request"]
Nov 24 07:25:09 p219 pppd[10520]: Connection terminated.
Nov 24 07:25:09 p219 pppd[10520]: Exit.
>>> sent Connect message
Nov 24 07:25:11 p219 ipcop: Starting RED device eth1.
Nov 24 07:25:11 p219 ipcop: Dialling iBurst.
Nov 24 07:25:11 p219 pppd[10720]: Plugin rp-pppoe.so loaded.
Nov 24 07:25:11 p219 pppd[10720]: RP-PPPoE plugin version 3.3 compiled against pppd 2.4.2
Nov 24 07:25:11 p219 pppd[10721]: pppd 2.4.2 started by root, uid 0
Nov 24 07:25:11 p219 pppd[10721]: PADS: Service-Name: ''
Nov 24 07:25:11 p219 pppd[10721]: PPP session is 23
Nov 24 07:25:11 p219 pppd[10721]: using channel 27
Nov 24 07:25:11 p219 pppd[10721]: Using interface ppp0
Nov 24 07:25:11 p219 pppd[10721]: Connect: ppp0 <--> eth1
Nov 24 07:25:11 p219 pppd[10721]: Couldn't increase MTU to 1500
Nov 24 07:25:11 p219 pppd[10721]: Couldn't increase MRU to 1500
Nov 24 07:25:11 p219 pppd[10721]: sent [LCP ConfReq id=0x1 <magic 0xb0898675>]
Nov 24 07:25:44 p219 last message repeated 11 times
Nov 24 07:26:29 p219 last message repeated 15 times
Nov 24 07:26:31 p219 kernel: eth1: link down
Nov 24 07:26:32 p219 pppd[10721]: sent [LCP ConfReq id=0x1 <magic 0xb0898675>]
Nov 24 07:26:38 p219 last message repeated 2 times
Nov 24 07:26:40 p219 kernel: eth1: link up, 100Mbps, full-duplex, lpa 0x41E1
Nov 24 07:26:41 p219 kernel: eth1: link down
Nov 24 07:26:41 p219 pppd[10721]: sent [LCP ConfReq id=0x1 <magic 0xb0898675>]
Nov 24 07:26:42 p219 kernel: eth1: link up, 10Mbps, half-duplex, lpa 0x0000
Nov 24 07:26:44 p219 pppd[10721]: sent [LCP ConfReq id=0x1 <magic 0xb0898675>]
Nov 24 07:27:11 p219 last message repeated 9 times
Nov 24 07:27:14 p219 pppd[10721]: Terminating on signal 15.
Nov 24 07:27:14 p219 pppd[10721]: sent [LCP TermReq id=0x2 "User request"]
Nov 24 07:27:17 p219 pppd[10721]: sent [LCP TermReq id=0x3 "User request"]
Nov 24 07:27:20 p219 pppd[10721]: Connection terminated.
Nov 24 07:27:20 p219 pppd[10721]: Exit.
>>>> acouple of Disconnect, Connect messages
Nov 24 07:27:22 p219 ipcop: Starting RED device eth1.
Nov 24 07:27:22 p219 ipcop: Dialling iBurst.
Nov 24 07:27:22 p219 pppd[11168]: Plugin rp-pppoe.so loaded.
Nov 24 07:27:22 p219 pppd[11168]: RP-PPPoE plugin version 3.3 compiled against pppd 2.4.2
Nov 24 07:27:22 p219 pppd[11169]: pppd 2.4.2 started by root, uid 0
Nov 24 07:27:38 p219 pppd[11169]: PADS: Service-Name: ''
Nov 24 07:27:38 p219 pppd[11169]: PPP session is 3
Nov 24 07:27:38 p219 pppd[11169]: using channel 28
Nov 24 07:27:38 p219 pppd[11169]: Using interface ppp0
Nov 24 07:27:38 p219 pppd[11169]: Connect: ppp0 <--> eth1
Nov 24 07:27:38 p219 pppd[11169]: Couldn't increase MTU to 1500
Nov 24 07:27:38 p219 pppd[11169]: Couldn't increase MRU to 1500
Nov 24 07:27:38 p219 pppd[11169]: sent [LCP ConfReq id=0x1 <magic 0x5d07efe8>]
Nov 24 07:27:44 p219 last message repeated 2 times
Nov 24 07:27:44 p219 pppd[11169]: rcvd [LCP ConfReq id=0x1 <mru 1392> <asyncmap 0x0> <auth pap> <magic 0x68fba3a5>] 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Nov 24 07:27:44 p219 pppd[11169]: sent [LCP ConfRej id=0x1 <asyncmap 0x0>]
Nov 24 07:27:44 p219 pppd[11169]: rcvd [LCP ConfAck id=0x1 <magic 0x5d07efe8>] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Nov 24 07:27:44 p219 pppd[11169]: rcvd [LCP ConfReq id=0x2 <mru 1392> <auth pap> <magic 0x68fba3a5>] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Nov 24 07:27:44 p219 pppd[11169]: sent [LCP ConfAck id=0x2 <mru 1392> <auth pap> <magic 0x68fba3a5>]
Nov 24 07:27:44 p219 pppd[11169]: Couldn't increase MRU to 1500
Nov 24 07:27:44 p219 pppd[11169]: sent [LCP EchoReq id=0x0 magic=0x5d07efe8]
Nov 24 07:27:44 p219 pppd[11169]: sent [PAP AuthReq id=0x1 user="zzzzz@wbs.co.za" password=<hidden>]
Nov 24 07:27:44 p219 pppd[11169]: rcvd [LCP EchoRep id=0x0 magic=0x68fba3a5] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Nov 24 07:27:45 p219 pppd[11169]: rcvd [PAP AuthAck id=0x1 ""] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ...
Nov 24 07:27:45 p219 pppd[11169]: PAP authentication succeeded
Nov 24 07:27:45 p219 pppd[11169]: peer from calling number 00:C0:EE:C3:6A:F1 authorized
......
Nov 24 07:27:45 p219 ipcop: PPP has gone up on ppp0
>>> Success!

And its been flying ever since......

 

[8321]

which log file?

I am looking at /var/log/messages.

Your log file looks a bit different. What path is it on?

 

[Unchained]

I see you use the pppoe plugin, the native kernel support also works fine and then you probably have more control over the unmentionables should you wish to apply it...

The logs have been copied from the web i/f, effectively also from /var/log/messages, just filtered...
Logs | System logs, then select Section: RED and update