Problèmes de deco/reco ECI USB et IPCop 1.4.0b3

[scal]

Bonjour à tous,

Et tout d'abord bravo pour ce forum qui m'a permi de trouver plein d'info intéressantes sur IPCop

Voilà, j'ai quelques problèmes avec mon ECI USB Hifocus, et j'ai pas vraiment trouvé de solution sur le forum. Et ce n'est pas faute d'avoir cherché et testé quelques trucs

Tout d'abord j'ai parfois des deconnexions intempestives, ce qui est relativement récent. Jusqu'içi je ne mettais pas en cause IPCop, mais après avoir vu les logs (voir plus bas), et avoir testé un autre FAI (Free) que le mien (Télé2), je n'en suis plus aussi sûr.
De plus, lors des ces deconnections et lors de la deconnexion des 24h, la reconnexion ne se fait pas systèmatiquement. je suis obligé de relancer manuellement le modem, c'est à dire de le resynchroniser, et cela prends quand même au moins 30 secondes. C'est donc très génant, et encore plus lorsque cela arrive à 3h19 du matin comme sur le log suivant :

Deconnexion intempestive :
Apr 25 03:19:53 ipcop pppd[5680]: No response to 3 echo-requests
Apr 25 03:19:53 ipcop pppd[5680]: Serial link appears to be disconnected.
Apr 25 03:19:53 ipcop snort: pcap_loop: recvfrom: Network is down
Apr 25 03:19:53 ipcop snort: Final Flow Statistics
Apr 25 03:19:53 ipcop snort: Snort exiting
Apr 25 03:19:53 ipcop pppd[5680]: Connection terminated.
Apr 25 03:19:53 ipcop pppd[5680]: Connect time 527.4 minutes.
Apr 25 03:19:53 ipcop pppd[5680]: Sent 25433297 bytes, received 67350601 bytes.
Apr 25 03:19:53 ipcop pppd[5680]: Using interface ppp0
Apr 25 03:19:53 ipcop pppd[5680]: Connect: ppp0 <--> /dev/pts/0
Apr 25 03:19:53 ipcop pppd[5680]: Modem hangup
Apr 25 03:19:53 ipcop pppd[5680]: Connection terminated.
Apr 25 03:19:53 ipcop pppd[5680]: Connect time 527.4 minutes.
Apr 25 03:19:53 ipcop pppd[5680]: Sent 25433297 bytes, received 67350601 bytes.
Apr 25 03:19:53 ipcop pppd[5680]: Using interface ppp0
Apr 25 03:19:53 ipcop pppd[5680]: Connect: ppp0 <--> /dev/pts/0
Apr 25 03:19:53 ipcop pppd[5680]: Modem hangup
Apr 25 03:19:53 ipcop pppd[5680]: Connection terminated.
Apr 25 03:19:53 ipcop pppd[5680]: Connect time 527.4 minutes.
Apr 25 03:19:53 ipcop pppd[5680]: Sent 25433297 bytes, received 67350601 bytes.
Apr 25 03:19:54 ipcop pppd[5680]: Using interface ppp0
Apr 25 03:19:54 ipcop pppd[5680]: Connect: ppp0 <--> /dev/pts/0
Apr 25 03:19:54 ipcop ipcop: PPP has gone down on ppp0
Apr 25 03:19:54 ipcop dnsmasq[8291]: started, version 1.18 cachesize 150
Apr 25 03:19:54 ipcop dnsmasq[8291]: read /etc/hosts - 3 addresses
Apr 25 03:19:54 ipcop dnsmasq[8291]: reading /var/ipcop/red/resolv.conf
Apr 25 03:19:54 ipcop dnsmasq[8291]: using nameserver 212.27.32.176#53
Apr 25 03:19:54 ipcop dnsmasq[8291]: using nameserver 213.228.0.23#53
Apr 25 03:19:54 ipcop dnsmasq[8291]: reading /var/state/dhcp/dhcpd.leases
Apr 25 03:20:00 ipcop fcron[8323]: Job /usr/local/bin/timecheck > /dev/null 2>&1 started for user root (pid 8324)
Apr 25 03:20:00 ipcop fcron[8328]: Job [ -f "/var/ipcop/red/active" ] && /usr/local/bin/setddns.pl >/var/log/dynupdate.log 2>&1
started for user root (pid 8329)
Apr 25 03:20:00 ipcop fcron[8325]: Job /usr/local/bin/makegraphs >/dev/null started for user root (pid 8326)
Apr 25 03:20:00 ipcop fcron[8328]: Job [ -f "/var/ipcop/red/active" ] && /usr/local/bin/setddns.pl >/var/log/dynupdate.log 2>&1
terminated (exit status: 1)
Apr 25 03:20:00 ipcop fcron[8323]: Job /usr/local/bin/timecheck > /dev/null 2>&1 terminated
Apr 25 03:20:13 ipcop fcron[8325]: Job /usr/local/bin/makegraphs >/dev/null terminated
Apr 25 03:21:24 ipcop pppd[5680]: Terminating on signal 15.
Apr 25 03:21:24 ipcop pppd[5680]: Child process /usr/bin/pppoeci -vpi 8 -vci 35 -vendor 0x0915 -product 0x8000 -mode VCM_RFC2364 (pid
8261) terminated with signal 11
Apr 25 03:21:24 ipcop pppd[5680]: Modem hangup
Apr 25 03:21:24 ipcop pppd[5680]: Connection terminated.
Apr 25 03:21:24 ipcop pppd[5680]: Connect time 527.4 minutes.
Apr 25 03:21:24 ipcop pppd[5680]: Sent 25433297 bytes, received 67350601 bytes.
Apr 25 03:21:24 ipcop pppd[5680]: Exit.



Reconnexion 24H foireuse :

Apr 29 13:27:05 ipcop pppd[473]: LCP terminated by peer
Apr 29 13:27:05 ipcop snort: pcap_loop: recvfrom: Network is down
Apr 29 13:27:05 ipcop snort: Final Flow Statistics
Apr 29 13:27:06 ipcop snort: Snort exiting
Apr 29 13:27:06 ipcop ipcop: PPP has gone down on ppp0
Apr 29 13:27:06 ipcop dnsmasq[7615]: started, version 1.18 cachesize 150
Apr 29 13:27:06 ipcop dnsmasq[7615]: read /etc/hosts - 3 addresses
Apr 29 13:27:06 ipcop dnsmasq[7615]: reading /var/ipcop/red/resolv.conf
Apr 29 13:27:06 ipcop dnsmasq[7615]: using nameserver 212.27.32.177#53
Apr 29 13:27:06 ipcop dnsmasq[7615]: using nameserver 213.228.0.212#53
Apr 29 13:27:06 ipcop dnsmasq[7615]: reading /var/state/dhcp/dhcpd.leases
Apr 29 13:27:08 ipcop pppd[473]: Connection terminated.
Apr 29 13:27:08 ipcop pppd[473]: Connect time 1433.3 minutes.
Apr 29 13:27:08 ipcop pppd[473]: Sent 1051227735 bytes, received 440130702 bytes.
Apr 29 13:27:08 ipcop pppd[473]: Using interface ppp0
Apr 29 13:27:08 ipcop pppd[473]: Connect: ppp0 <--> /dev/pts/1
Apr 29 13:27:08 ipcop pppd[473]: Modem hangup
Apr 29 13:27:08 ipcop pppd[473]: Connection terminated.
Apr 29 13:27:08 ipcop pppd[473]: Connect time 1433.3 minutes.
Apr 29 13:27:08 ipcop pppd[473]: Sent 1051227735 bytes, received 440130702 bytes.
Apr 29 13:27:08 ipcop pppd[473]: Using interface ppp0
Apr 29 13:27:08 ipcop pppd[473]: Connect: ppp0 <--> /dev/pts/1
Apr 29 13:27:08 ipcop pppd[473]: Modem hangup
Apr 29 13:27:08 ipcop pppd[473]: Connection terminated.
Apr 29 13:27:09 ipcop pppd[473]: Connect time 1433.3 minutes.
Apr 29 13:27:09 ipcop pppd[473]: Sent 1051227735 bytes, received 440130702 bytes.
Apr 29 13:27:09 ipcop pppd[473]: Using interface ppp0
Apr 29 13:27:09 ipcop pppd[473]: Connect: ppp0 <--> /dev/pts/0
Apr 29 13:30:00 ipcop fcron[7666]: Job /usr/local/bin/timecheck > /dev/null 2>&1 started for user root (pid 7667)
Apr 29 13:30:00 ipcop fcron[7670]: Job [ -f "/var/ipcop/red/active" ] && /usr/local/bin/setddns.pl >/var/log/dynupdate.log 2>&1 started for user root (pid 7673)
Apr 29 13:30:00 ipcop fcron[7668]: Job /usr/local/bin/makegraphs >/dev/null started for user root (pid 7669)
Apr 29 13:30:00 ipcop fcron[7670]: Job [ -f "/var/ipcop/red/active" ] && /usr/local/bin/setddns.pl >/var/log/dynupdate.log 2>&1 terminated (exit status: 1)
Apr 29 13:30:00 ipcop fcron[7666]: Job /usr/local/bin/timecheck > /dev/null 2>&1 terminated
Apr 29 13:30:12 ipcop fcron[7668]: Job /usr/local/bin/makegraphs >/dev/null terminated
Apr 29 13:35:00 ipcop fcron[7717]: Job /usr/local/bin/timecheck > /dev/null 2>&1 started for user root (pid 7718)
Apr 29 13:35:00 ipcop fcron[7721]: Job [ -f "/var/ipcop/red/active" ] && /usr/local/bin/setddns.pl >/var/log/dynupdate.log 2>&1 started for user root (pid 7723)
Apr 29 13:35:00 ipcop fcron[7719]: Job /usr/local/bin/makegraphs >/dev/null started for user root (pid 7720)
Apr 29 13:35:00 ipcop fcron[7721]: Job [ -f "/var/ipcop/red/active" ] && /usr/local/bin/setddns.pl >/var/log/dynupdate.log 2>&1 terminated (exit status: 1)
Apr 29 13:35:00 ipcop fcron[7717]: Job /usr/local/bin/timecheck > /dev/null 2>&1 terminated
Apr 29 13:35:15 ipcop fcron[7719]: Job /usr/local/bin/makegraphs >/dev/null terminated
Apr 29 13:40:00 ipcop fcron[7786]: Job /usr/local/bin/timecheck > /dev/null 2>&1 started for user root (pid 7787)
Apr 29 13:40:00 ipcop fcron[7790]: Job [ -f "/var/ipcop/red/active" ] && /usr/local/bin/setddns.pl >/var/log/dynupdate.log 2>&1 started for user root (pid 7792)
Apr 29 13:40:00 ipcop fcron[7788]: Job /usr/local/bin/makegraphs >/dev/null started for user root (pid 7789)
Apr 29 13:40:00 ipcop fcron[7790]: Job [ -f "/var/ipcop/red/active" ] && /usr/local/bin/setddns.pl >/var/log/dynupdate.log 2>&1 terminated (exit status: 1)
Apr 29 13:40:00 ipcop fcron[7786]: Job /usr/local/bin/timecheck > /dev/null 2>&1 terminated
Apr 29 13:40:13 ipcop fcron[7788]: Job /usr/local/bin/makegraphs >/dev/null terminated


Ma config :
PII400@266 MHz (underclocké) - 80 Mo SDRAM - 270 Mo HDD - 2 x Realtek 8139 - Carte USB PCI Opti - ECI USB Hifocus - GREEN + ORANGE (Red is a modem/ISDN)
Snort sur Red, pas de DHCP ni de proxy web
je suis à 2km du central téléphonique et j'ai une attenuation faible (je suis en 512 mais éligible au 1024)

Voilà j'espère qu'avec toutes ces infos quelqu'un pourra m'aider, parce que IPCop c'est vraiment génial

[Gesp]

Je pense qu'il y a plusieurs problèmes :
1 - le modem ECI est très gourmand en courant, ce qui pourrait être une cause des déconnexions
Est-ce que tu as des messages 'EMI' dans ton log

cat /var/log/messages | grep EMI

Je pense que c'est la cause des 'Modem hangup' immédiats.

Si tu mets moins de reconnexions, le script de rc.connectioncheck te reconnectera plus vite parce que c'est actuellement le seul moyen de repérer le modem en faisant d'abord eci-load1 eci-load2

Mon script pourrait être amélioré, j'ai des solutions, c'est dans mes priorités.

Après cela n'explique pas les problèmes de reco aux 24h.
Dans ce cas, c'est peut-être le peer qui pose problème. As-tu mis en place le 'lissage de trafic'?

[scal]

Merci pour cette réponse rapide

Alors, je n'ai pas de messages EMI dans mon log.
Et j'ai écarté ce problème depuis longtemps puisqu'en fait j'utilise ce couple carte PCI-USB/Modem ECI-USB depuis au moins 2 ans, et que je n'ai jamais recontré aucun problème de déco avec ce modem pendant tout ce temps là. Evidement j'étais sous Windows, mais quand même, ce couple a fonctionné sur plusieur PCs différents sans poser de problème particulier. D'ailleurs j'ai aussi testé IPcop sur un Duron avec de l'USB 2.0 avec une alim de 400W et j'avais le mêmê problème.

Si tu mets moins de reconnexions, le script de rc.connectioncheck te reconnectera plus vite parce que c'est actuellement le seul moyen de repérer le modem en faisant d'abord eci-load1 eci-load2

Je vais tester ça, merci.

Sinon le lissage de traffic n'étais pas activé.

Mais que signifie ces deux lignes ? :
Apr 25 03:19:53 ipcop pppd[5680]: No response to 3 echo-requests
Apr 25 03:19:53 ipcop pppd[5680]: Serial link appears to be disconnected.

Est-ce que cela signifie que le modem ne réponds plus ?
Ce ne serait pas plutot un problème lié au driver ?

[coolpok]

Je croit que pour la 1.4.0b3 ce probleme est réglé en modifiant le script :
/var/ipcop/header.pl
tu supprimes la ligne 'use locale' du fichier
ce qui te donnera ca :

Code: Tout sélectionner

{
my $logmessage = $_[0];
$logmessage =~ /([\w\W]*)/;
$logmessage = $1;
system('/usr/bin/logger', '-t', 'ipcop', $logmessage);
}

au lieu de ca:

Code: Tout sélectionner

{
use locale;
my $logmessage = $_[0];
$logmessage =~ /([\w\W]*)/;
$logmessage = $1;
system('/usr/bin/logger', '-t', 'ipcop', $logmessage);
}

voila

[Gesp]

c'est possible que l'erreur 512 sur 'use locale' pose des problèmes à chaque fois que IPCop émet un message de log.

Mais c'est peu probable dans le cas. Par exemple quand pppd tente de se reconnecter plusieurs fois, les scripts PERL n'interviennent pas.