[wiregate] Wiregate Arbeitsspeicher läuft bis zum Absturz voll?

[JuMi2006]

Eben ist mein Wiregate abgeschmiert
Ich tippe mal auf den Squeezebox-Server ... zumindest taucht der ständig im Log auf.

Hier mal das Diagramm Memory used/free.

Wie kann ich das verfolgen? Einfach im Webmin mal nachsehen wie/ob der Speicherbedarf steigt? War auf jeden Fall ziemlich unbequem eben ... alles dunkel weil iButtons abgemeldet (owserver ???).

@makki: Interesse am syslog ?

[makki]

Der wiregated*.pl wird seit PL31 auch als RRD aufgezeichnet (graph2.pl); und schon immer im monit überwacht, was es ist: schwer zu sagen, sieht man auch im nachhinein nicht unbedingt gut aus den Logs. Weil der OOM (Out Of Memory killer) schlägt dann ziemlich flächig zu und trifft nicht unbedingt den richtigen..

collectd kann auch Aufschluss geben: http://wiregateXXX/collection3/bin/index.cgi

Erste Massnahme: den squeezy in den monit packen, Vorlage: /etc/monit/enable/wiregated, einfach kopieren und dort als squeezeserver speichern, Inhalt sollte angepasst werden aber selbsterklärend sein
Ohne Monitoring läuft auf dem WG an zentralen Sachen wenig, ein zuverlässiger Hausmeister ist das A und O..

Makki

[JuMi2006]

Mach ich die Tage mal ... jetzt muss ich erstmal die Frau beruhigen ... die ist gefrustet weil sie jetzt Werbung gucken muss.
Sonst kann man das Dank Timeshift immer so schön "überblättern" Aber so ein Blackout hinterlässt auch spuren.

[JuMi2006]

Hallo Makki,

so wie es aussieht bereitet der collectd Probleme.
Im Anhang mal ein Auszug aus dem deamon.log. Das einzige was ich gestern gemacht habe waren Einträge in etc/udev/rules.d um meine USB-Sticks unter festem Namen zu mounten.

Gruß mirko

Edit: wiregate403

[StefanW]

Bitte einen geeigneteren Thread-Titel vergeben. Weil "Wer zieht hier wem den Stecker" ist zu allgemein

Danke

Stefan

(der froh wäre, wenn sich alle auch ohne Moderation an die Forenregeln halten würden, damit ich Zeit für wichtigeres habe)

[makki]

Das sind nur Symptome, wenn der OOM kommt ists zu spät;
entweder dem squeezeboxserver manieren beibringen oder es geht eben nicht..

sowas wie:
/etc/monit/enable/squeezeboxserver

check process squeezeboxserver with pidfile /var/run/logitechmediaserver.pid
    start program = "/etc/init.d/logitechmediaserver restart"
    stop program  = "/etc/init.d/logitechmediaserver stop"
    if cpu > 60% for 2 cycles then alert
    if totalmem > 96.0 MB for 2 cycles then restart
    if loadavg(5min) greater than 2 for 8 cycles then restart
    if children > 100 then restart

Makki

[JuMi2006]

Ich hab den jetzt ganz aus, wird aber nicht besser

[JuMi2006]

Nachdem jetzt dauernd socat3 Error kamen hab ich jetzt einfach mal das lange USB-Kabeln zu den Zählern gegen nen kurzes quer durch den Raum getauscht ... jetzt mal Neustart und abwarten was morgen früh so anliegt.

[makki]

Schwer zu sagen, da sieht alles gut aus und der USB alleine reisst die Kiste nicht..
Aber es hängt halt schon auch ne Menge Stoff am Billig-hub;
Also nur so für den geneigten Mitleser: 2 FTDI, UMTS-Stick, 3 Speichersticks, Cardreader, ...

UMTS-Sticks sind dafür bekannt sich nicht zwingend an die 500mA zu halten und diverse USB-Hubs (bei meinen Tests mit so 15, mithin 90%) haben nicht das an Strom geliefert, was sie sollten..
Da könnte man ggfs. auch ansetzen..

Makki

[JuMi2006]

Naja dafür aber mit Netzteil. Selbst ohne USB-Geräte steigt der collectd alle 5 Minuten aus. Ändert nix. Was mich wunder ist dass es so plötzlich kommt.

Soll der collectd mal ausgeschalten werden und dann mal gucken was der Speicher macht??? Womit wird das rrd für den wiregated erstellt?

[NetFritz]

Hallo
Das Problem mit dem Speicher voll hatte auch schon.
Bei mir war es das sml_zaehler.pl Script.
Das es irgendwie nicht beendet wurde, Cron hat es mehrmals aufgerufen.

ps ax | grep pl

in der Konsole zeigte mir viele sml_zaehler.pl Scripte an.
Gruß NetFritz

[swiss]

Ich hatte 2 mal Speicher voll wegen FAMd hatt makki wieder hingebogen. Seit dem läuft mein WG wieder normal.

[JuMi2006]

Also Jetzt lief das WG mal mit folgenden Parametern:

• collectd deaktiviert
• squeezeboxserver wird von monit überwacht - loadavg(5min) auf 5 gesetzt um ein paar Fehlermeldungen weniger zu erhalten.
• nach erstem Test dann wieder das volle USB-Programm (2 x usb-serial, 1 x sd-card, 1 x usb-stick, 3 x busmaster, 1 x umts-stick)

Bislang fehlerfrei und monit zieht dem wiregated regelmäßig den Stecker (alle 12h) das wird aber wohl am Squeezebox-Plugin liegen.

Das Squeezebox-Plugin schafft es dann wohl den wiregated volllaufen zu lassen: Mbi: 48.3 Ticks: 104088

Jetzt deaktiviere ich mal das Plugin und starte den wiregated neu. Mal sehen ob er dann weniger schnell an Speicher zunimmt.
collectd bleibt auch erstmal aussen vor.

Die restliche Speicherentwicklung ist erstmal unauffällig soweit ich das erkennen kann.

@NetFritz: Das hatte ich zwischenzeitlich auch mal, vor allem beim Entwickeln. Cron scheint da ziemlich schmerzfrei zu sein.

Gruß Mirko

[JuMi2006]

Also der wiregated verhält sich ohne Squeezebox-Plugin normal ... steigt also nur gemächlich im Speicherverbrauch. Zwischendurch war ich so mutig den collectd wieder zu aktivieren, der arbeitet aber einfach nicht richtig
Das hier macht er alle 10 Minuten:
Spannend ist dass 1344160242 eigentlich 11:50:42 ist und im log 11:55:42 auftaucht.

[FONT="Courier New"][SIZE="2"]Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/users/users.rrd) failed: /var/lib/collectd/rrd/wiregate403/users/users.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/cpu-0/cpu-user.rrd) failed: /var/lib/collectd/rrd/wiregate403/cpu-0/cpu-user.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/cpu-0/cpu-nice.rrd) failed: /var/lib/collectd/rrd/wiregate403/cpu-0/cpu-nice.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/cpu-0/cpu-system.rrd) failed: /var/lib/collectd/rrd/wiregate403/cpu-0/cpu-system.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/cpu-0/cpu-idle.rrd) failed: /var/lib/collectd/rrd/wiregate403/cpu-0/cpu-idle.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/cpu-0/cpu-wait.rrd) failed: /var/lib/collectd/rrd/wiregate403/cpu-0/cpu-wait.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/cpu-0/cpu-interrupt.rrd) failed: /var/lib/collectd/rrd/wiregate403/cpu-0/cpu-interrupt.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/cpu-0/cpu-softirq.rrd) failed: /var/lib/collectd/rrd/wiregate403/cpu-0/cpu-softirq.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/cpu-0/cpu-steal.rrd) failed: /var/lib/collectd/rrd/wiregate403/cpu-0/cpu-steal.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/load/load.rrd) failed: /var/lib/collectd/rrd/wiregate403/load/load.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_octets.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_octets.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_packets.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_packets.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_errors.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_errors.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_dropped.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_dropped.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_multicast.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_multicast.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_collisions.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_collisions.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-length.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-length.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-over.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-over.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-crc.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-crc.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-frame.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-frame.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-fifo.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-fifo.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-missed.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_rx_errors-missed.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_tx_errors-aborted.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_tx_errors-aborted.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_tx_errors-carrier.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_tx_errors-carrier.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/netlink-eth0/if_tx_errors-fifo.rrd) failed: /var/lib/collectd/rrd/wiregate403/netlink-eth0/if_tx_errors-fifo.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/entropy/entropy.rrd) failed: /var/lib/collectd/rrd/wiregate403/entropy/entropy.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/df/df-root.rrd) failed: /var/lib/collectd/rrd/wiregate403/df/df-root.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/df/df-lib-init-rw.rrd) failed: /var/lib/collectd/rrd/wiregate403/df/df-lib-init-rw.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/memory/memory-used.rrd) failed: /var/lib/collectd/rrd/wiregate403/memory/memory-used.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/memory/memory-buffered.rrd) failed: /var/lib/collectd/rrd/wiregate403/memory/memory-buffered.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/memory/memory-cached.rrd) failed: /var/lib/collectd/rrd/wiregate403/memory/memory-cached.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectd[23197]: rrdtool plugin: rrd_update_r (/var/lib/collectd/rrd/wiregate403/memory/memory-free.rrd) failed: /var/lib/collectd/rrd/wiregate403/memory/memory-free.rrd: illegal attempt to update using time 1344160242 when last update time is 1344160242 (minimum one second step)
Aug  5 11:55:42 wiregate403 collectdmon[28911]: Warning: collectd was terminated by signal 8
Aug  5 11:55:42 wiregate403 collectdmon[28911]: Warning: restarting collectd
Aug  5 11:55:43 wiregate403 collectd[29108]: Initialization complete, entering read-loop.
Aug  5 11:55:43 wiregate403 collectd[29108]: battery plugin: Failed to access `/proc/acpi/battery': No such file or directory
Aug  5 11:55:43 wiregate403 collectd[29108]: unixsock plugin: bind failed: Address already in use[/SIZE][/FONT]

[greentux]

Kannst Du nicht irgendwie mal das rrdtool etwas zähmen? So ein Müll im Log