komische maximallast von perl - kennt das wer?

[the ratman]

hiho,

ich hatte gestern avebd um punkt 21:30 uhr auf einmal perl auf maximaler last laufen (25%, weil nur 1 kern) - siehe abhang

in 90% der zeit war perl daran schuld, im rest der zeit cron.
kein fhem-restart, kein raspi-reboot, nix brachte was.
und dann gings auf einmal wieder von ganz alleine.

kennt das wer, was könnte das sein?
was kann ich gegen tun?

danke für eventuelle infos!

[Wernieman]

Sorry aber zuwenig Info, um es nachträglich zu erklähren.

Gut wäre eine Prozessliste, zum Zeitpunkt "des Problems":

Code Auswählen Erweitern

ps aux

Auf jedem Falle hat in der Zeit Dein Gerät etwas getan ...

[the ratman]

ich hatte grade nen taskmanager offen.
wie gesagt: alle prozesse liefen stink normal von den %, nur perl lief bei 25%, also 1 kern 100%.
auffällig war, dass eben mal der prozess "cron" für ca. 5 bis 10 min die 25% übernommen hatte und zu der zeit perl wieder bei den üblichen 1 bis 3% rumgetümpelt ist.
ich hab aber (zumindest selber) keine cronjobs angelegt auf dem raspi.
was vielleicht noch interessant wäre: den ganzen tag hab ich nix am system rumgedreht, kein device angelegt/gelöscht/geändert, einfach nix getan. so sieht auch gleich das fhem-log aus, wenn man von den müllmeldungen des dlna-renderers absieht. aber die sind auch ned neu oder gestern anders als sonst gewesen ( https://forum.fhem.de/index.php/topic,72423.msg640638.html#msg640638 ).

ich hab auch eher gehofft, dass ich gesagt krieg, wie ich rauskriegen kann, warum perl auf einmal so ne last macht.
linux hat ja sonst auch für jeden scheiß x befehle. da gibts doch sicher auch was, wo ich mir genauer ansehen kann, was perl zu solchen eskapaden getrieben hat.

[Wernieman]

Wenn Cron Zeit brauchst, dann läuft ein Cron.

Schau mal unter

Code Auswählen Erweitern

ls -lha /etc/cron*
ls -lha /var/spool/cron/crontabs

Und wie schon gesagt, zur Vergangenheit kann man wenig sagen ... und auch perl ist ""nur" eine Programmiersprache. D.h. nicht perl. sondern das in Perl-Programmierte Programm macht diese Auslastung. Fhem ist eines von diesen.

[the ratman]

is mir schon klar, dass im nachhinein schwer was zu sagen geht. drum auch gleich thx für deine mühen!
mir gehts - wie gsagt - eher auch darum für das nächste mal gewappnet zu sein. vielleicht verfall ich dann nicht gleich wieder in panik und mach vernünftiges *g*.

aja, hab deine 2 befehle mal ausgeführt. vielleicht siehst du da ja komisches?

Code Auswählen Erweitern

pi@ratOhaus:~ $ ls -lha /etc/cron*
-rw-r--r-- 1 root root  722 Sep  5  2015 /etc/crontab

/etc/cron.d:
insgesamt 24K
drwxr-xr-x   2 root root 4,0K Apr 21 13:02 .
drwxr-xr-x 119 root root  12K Mai 25 12:08 ..
-rw-r--r--   1 root root  661 Apr 14 20:31 php5
-rw-r--r--   1 root root  102 Sep  5  2015 .placeholder

/etc/cron.daily:
insgesamt 68K
drwxr-xr-x   2 root root 4,0K Mai 25 12:08 .
drwxr-xr-x 119 root root  12K Mai 25 12:08 ..
-rwxr-xr-x   1 root root  15K Dez 13 23:38 apt
-rwxr-xr-x   1 root root  314 Jun 15  2014 aptitude
-rwxr-xr-x   1 root root  355 Okt 28  2014 bsdmainutils
-rwxr-xr-x   1 root root 1,6K Jun  5  2016 dpkg
-rwxr-xr-x   1 root root   89 Jan 28  2014 logrotate
-rwxr-xr-x   1 root root 1,3K Jan  6  2015 man-db
-rwxr-xr-x   1 root root 1,1K Jul 26  2016 ntp
-rwxr-xr-x   1 root root  249 Feb 24 23:49 passwd
-rw-r--r--   1 root root  102 Sep  5  2015 .placeholder
-rwxr-xr-x   1 root root  383 Apr  2 19:50 samba

/etc/cron.hourly:
insgesamt 24K
drwxr-xr-x   2 root root 4,0K Apr 10 11:25 .
drwxr-xr-x 119 root root  12K Mai 25 12:08 ..
-rwxr-xr-x   1 root root  191 Mär 16  2015 fake-hwclock
-rw-r--r--   1 root root  102 Sep  5  2015 .placeholder

/etc/cron.monthly:
insgesamt 20K
drwxr-xr-x   2 root root 4,0K Apr 10 11:14 .
drwxr-xr-x 119 root root  12K Mai 25 12:08 ..
-rw-r--r--   1 root root  102 Sep  5  2015 .placeholder

/etc/cron.weekly:
insgesamt 24K
drwxr-xr-x   2 root root 4,0K Apr 10 11:14 .
drwxr-xr-x 119 root root  12K Mai 25 12:08 ..
-rwxr-xr-x   1 root root  771 Jan  6  2015 man-db
-rw-r--r--   1 root root  102 Sep  5  2015 .placeholder


Code Auswählen Erweitern

sudo ls -lha /var/spool/cron/crontabs
insgesamt 8,0K
drwx-wx--T 2 root crontab 4,0K Sep  5  2015 .
drwxr-xr-x 3 root root    4,0K Apr 10 11:13 ..


[Wernieman]

Jede Datei, Die nicht mit einem "." Anfängt, ist eine Crondefinition. Diese dienen zur Systempflege. Kannst Dir ja mal mit "cat" ansehen. Aber mir fällt dabei nichts auf ...

[the ratman]

dann dank ich dir fürs drüber schaun. das beruhigt schon mal ein kleines bissi

ist cat ned irgendwas zum dateien zusammenlegen oder so?

[Wernieman]

Neee ... "cat" ist zum anzeigen
https://de.wikipedia.org/wiki/Cat_(Unix)

Das mann damit Dateien zusammenlegen kann, ist eher ein "Abfallprodukt"

[the ratman]

ich hab meinen raspi gerade wieder mit 1 voll ausgelasteten kern erwischt. seit meinem letzten beitrag hier war ruhe.
prozess perl, user fhem

ein strace müllt mit folgendem voll:

Code Auswählen Erweitern

open("./www/images/fhemSVG/rc_dot.svg", O_RDONLY|O_LARGEFILE) = 47
ioctl(47, SNDCTL_TMR_TIMEBASE or SNDRV_TIMER_IOCTL_NEXT_DEVICE or TCGETS, 0x7eea78a4) = -1 ENOTTY (Inappropriate ioctl for device)
_llseek(47, 0, [0], SEEK_CUR)           = 0
fstat64(47, {st_mode=S_IFREG|0777, st_size=1062, ...}) = 0
fcntl64(47, F_SETFD, FD_CLOEXEC)        = 0
read(47, "<?xml version=\"1.0\" encoding=\"UT"..., 8192) = 1062
read(47, "", 8192)                      = 0
close(47)   und dazwischen immer mal wieder

Code Auswählen Erweitern

brk(0x56ab000)                          = 0x56ab000
brk(0x564c000)                          = 0x564c000
read(9, "E461363,0000,0003BB0C,FF,FFCD,4B"..., 4096) = 58
gettimeofday({1497185015, 975057}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 975381}, NULL) = 0
gettimeofday({1497185015, 975583}, NULL) = 0
gettimeofday({1497185015, 976009}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 976467}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 976702}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 977361}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 979764}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 980654}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 981715}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 982036}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 983188}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 983959}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 984742}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 985123}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 986911}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 987352}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 990521}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 991217}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 991739}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 992117}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 995018}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 995683}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 996209}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 996560}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 998325}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 998775}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185016, 497}, NULL)   = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185016, 949}, NULL)   = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185016, 3099}, NULL)  = 0
gettimeofday({1497185016, 3515}, NULL)  = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185016, 3781}, NULL)  = 0
gettimeofday({1497185016, 4414}, NULL)  = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185016, 4902}, NULL)  = 0

kann das mein problem sein? und wenn ja, wie löse ich das?

[the ratman]

ich glaub ich spinne

ich schau mal so alles durch, wo ich rc_dot_svg verwendet hab und finde eine rg mit
'state.closed' => 'rc_dot@green ',
ich entsorge den space am ende - hab ich wohl versehentlich irgendwann mal gemacht, restarte fhem (hat sonst auch nie was gebracht, nicht mal n reset) und ruhe is ...

zufall oder wars echt der eine blöde space? so einfach kann das bei linux doch nicht sein?

wenn es das war - kann man den fehler nicht irgendwie abfangen? das findet ja kein mensch und ein space wäre für einen temperaturanstieg meines raspi um 20° zuständig - ich denke, das könnt ins auge gehen.

[Wernieman]

Kann Dir nicht weiterhelfen, aber möchte ein paar Klarstellungen:
Was Du schreibst ist perl, nicht Linux. Unter Windows hättest Du damit das gleiche "Problem" (oder eventuell sogar mehr)

[the ratman]

scheint tatsächlich kein zufall gewesen zu sein - fhem tuckert brav mit seinen <3% last rum.
die zeiten stimmen übrigens mit meinen "türschließungen wegen aussenhitze" zusammen, es wird also wirklich glaubhafter.

und ja, deine klarstellung ist mir schon klar. ich bin und bleib aber ein begeisterter dau, drum sind mir genaue begriffe egal, oder nicht cool genug zum verwenden *g*
wenn bei windoof was nicht geht (was bei meinen installationen eher selten is), dann geb ich definitiv apple und/oder auch linux  und/oder sonnenflecken die schuld - nur, um das auch mal klargestellt zu haben ...

mal ernsthaft wieder:
du, mir gehts eher darum, dass einer ders wiklich blickt, das ganze eventuell nachprüft und - so ein space wirklich ein problem is - eventuelle sicherheitesroutinen in fhem einbaut.
man könnte natürlich auch ein modul draus machen, 01_ZUSATZHEIZUNG oder so. solang die raspis nicht ganz abbrennen, hat man somit einen mehrwert im winter oder sogar ein kleines, romantisches kaminfeuerchen.

[Wernieman]

Da möchte ich Dir eine Klarstellung geben, warum es eben nicht nur "cool" sondern wichtig ist, es zu unterscheiden:

Stell Dir mal vor, Dein Auto (im Beispiel BMW) hat einen Platten. Fragst Du auch jetzt im Netz um Hilfe, weil Dein BMW kaputt ist? Wunderst Du Dich auch, wenn Dir Helfe den Unterschied zwischen BWM (Auto) und Reifen sagen wollen?

[the ratman]

nö, ich lass meinen fahren den öamtc anrufen und schimpfe den lohnsklaven dann, dass er so lange gebraucht hat. *g*

[the ratman]

so, ums mal wieder ernst werden zu lassen.

meine probleme waren nur scheinbar behoben - ich hab wieder meinen 1 kern voll ausgelastet und keine ahnung, was ich tun soll.
langsam steigt die temp. meines raspi in unangenehme höhen.

hat irgendwer zumindest eine ahnung, in welche richtung ich generell suchen muß?
und könnte mir bitte einer der wissenden hier eine art rezept geben, was ich alles abrufen/dokumentieren muß, damit man mir helfen kann.
ich steh total an und muß sonst fhem ganz abschalten, bevor was über nacht passiert ...

[Wernieman]

Stichwort: apptime

[the ratman]

ah, thx

da kommt dann:

Code Auswählen Erweitern

name                                     function                               max  count    total  average maxDly TS Max call     param Max call
WEB_192.168.178.35_40794                 FW_Read                                516     52     2399    46.13      0 14.06. 21:56:48 HASH(WEB_192.168.178.35_40794)
WEB_192.168.178.35_40795                 FW_Read                                493     51      959    18.80      0 14.06. 21:56:45 HASH(WEB_192.168.178.35_40795)
WEB_192.168.178.35_40793                 FW_Read                                482     48     1429    29.77      0 14.06. 21:56:45 HASH(WEB_192.168.178.35_40793)
WEB_192.168.178.35_40796                 FW_Read                                464     51      482     9.45      0 14.06. 21:56:46 HASH(WEB_192.168.178.35_40796)
WEB_192.168.178.35_40792                 FW_Read                                462     46      473    10.28      0 14.06. 21:56:50 HASH(WEB_192.168.178.35_40792)
WEB_192.168.178.35_40791                 FW_Read                                448     55      464     8.44      0 14.06. 21:56:52 HASH(WEB_192.168.178.35_40791)
tmr-MPD_watch_idle                       HASH(0x3f40168)                         45      1       45    45.00    135 14.06. 21:56:53 HASH(Luke7_Stream)
tmr-LGTV_WebOS_TimerStatusRequest        HASH(0x3c522d0)                         41      1       41    41.00      1 14.06. 21:56:44 HASH(Fembotter)
Fembotter                                LGTV_WebOS_Read                         18      5       57    11.40      0 14.06. 21:56:52 HASH(Fembotter)
tmr-DbLog_execmemcache                   HASH(0x13ac990)                         13      1       13    13.00      1 14.06. 21:56:52 HASH(logdb)
moRat_Geraetestatus                      readingsGroup_Notify                    11     10       11     1.10      0 14.06. 21:56:46 HASH(moRat_Geraetestatus); HASH(Ping_ratOtab)
HMUSB                                    HMLAN_Read                               9      1        9     9.00      0 14.06. 21:56:46 HASH(HMUSB)
WEB_192.168.178.30_4747                  FW_Read                                  3      1        3     3.00      0 14.06. 21:56:44 HASH(WEB_192.168.178.30_4747)
WEB                                      FW_Read                                  1      1        1     1.00      0 14.06. 21:56:44 HASH(WEB)
WEB_192.168.178.26_60304                 FW_Notify                                1     10        1     0.10      0 14.06. 21:56:46 HASH(WEB_192.168.178.26_60304); HASH(moRat_Geraetestatus)
WEB_192.168.178.30_4739                  FW_Read                                  1      2        1     0.50      0 14.06. 21:56:44 HASH(WEB_192.168.178.30_4739)
tmr-BlockingKill                         HASH(0x148c0a0)                          1      1        1     1.00    298 14.06. 21:56:47 HASH(0x148c0a0)
tmr-HMLAN_KeepAlive                      keepAlive:HMUSB                          1      1        1     1.00    472 14.06. 21:56:46 keepAlive:HMUSB
tmr-LGTV_WebOS_Get3DStatus               HASH(0x3c522d0)                          1      1        1     1.00     54 14.06. 21:56:50 HASH(Fembotter)
tmr-LGTV_WebOS_GetCurrentChannel         HASH(0x3c522d0)                          1      1        1     1.00    241 14.06. 21:56:46 HASH(Fembotter)
tmr-LGTV_WebOS_GetExternalInputList      HASH(0x3c522d0)                          1      1        1     1.00      2 14.06. 21:56:52 HASH(Fembotter)

noch ein 2. mal sicherheitshalber:

Code Auswählen Erweitern

name                                     function                               max  count    total  average maxDly TS Max call     param Max call
WEB_192.168.178.35_40793                 FW_Read                                503      5      965   193.00      0 14.06. 21:59:03 HASH(WEB_192.168.178.35_40793)
WEB_192.168.178.35_40792                 FW_Read                                484      5      948   189.60      0 14.06. 21:59:04 HASH(WEB_192.168.178.35_40792)
WEB_192.168.178.35_40794                 FW_Read                                482      3     1408   469.33      0 14.06. 21:59:05 HASH(WEB_192.168.178.35_40794)
WEB_192.168.178.35_40795                 FW_Read                                476      5      941   188.20      0 14.06. 21:59:07 HASH(WEB_192.168.178.35_40795)
WEB_192.168.178.35_40791                 FW_Read                                466      3      929   309.67      0 14.06. 21:59:04 HASH(WEB_192.168.178.35_40791)
WEB_192.168.178.35_40796                 FW_Read                                461      5      464    92.80      0 14.06. 21:59:02 HASH(WEB_192.168.178.35_40796)
AMADCommBridge_192.168.178.26_58366      AMAD_CommBridge_Read                    19      1       19    19.00      0 14.06. 21:59:07 HASH(AMADCommBridge_192.168.178.26_58366)
tmr-FRITZBOX_Readout_Start               FritzBox.Readout                        11      1       11    11.00     93 14.06. 21:59:03 FritzBox.Readout
Fembotter                                LGTV_WebOS_Read                         10      2       10     5.00      0 14.06. 21:59:07 HASH(Fembotter)
tmr-BlockingKill                         HASH(0x48ecf20)                          2      1        2     2.00    375 14.06. 21:59:04 HASH(0x48ecf20)
telnetForBlockingFn_1497470151_127.0.0.1_52914 telnet_Read                              2      6        2     0.33      0 14.06. 21:59:05 HASH(telnetForBlockingFn_1497470151_127.0.0.1_52914)
AMADCommBridge                           AMAD_CommBridge_Read                     1     10        1     0.10      0 14.06. 21:59:04 HASH(AMADCommBridge)
DLNASocket-dlnadevices-23                DLNARenderer_Read                        1      2        1     0.50      0 14.06. 21:59:06 HASH(DLNASocket-dlnadevices-23)
WEB_192.168.178.30_4766                  FW_Read                                  1      2        1     0.50      0 14.06. 21:59:04 HASH(WEB_192.168.178.30_4766)
tmr-BlockingKill                         HASH(0x49375b0)                          1      1        1     1.00    157 14.06. 21:59:05 HASH(0x49375b0)
tmr-LGTV_WebOS_Get3DStatus               HASH(0x3c522d0)                          1      1        1     1.00    397 14.06. 21:59:05 HASH(Fembotter)
tmr-LGTV_WebOS_GetExternalInputList      HASH(0x3c522d0)                          1      1        1     1.00    311 14.06. 21:59:07 HASH(Fembotter)
tmr-LGTV_WebOS_GetForgroundAppInfo       HASH(0x3c522d0)                          1      1        1     1.00    377 14.06. 21:59:03 HASH(Fembotter)
AbfallReminder                           DOIF_Notify                              0      8        0     0.00      0
Abfallinfo                               ABFALL_Notify                            0      8        0     0.00      0
AnrufInfo_DOIF                           DOIF_Notify                              0      8        0     0.00      0


nachtrag:
ich hatte fhem in der nacht aus ... jetzt eben eingeschaltet wieder, morgendliches update gemacht ... rennt wieder.
am abschalten allein kanns ja ned liegen. das hab ich schon x mal gestern gemacht. inkl. raspi restarten und ganz vom strom nehmen.
das problem bringt micht echt um den verstand.

[Wernieman]

Sorry aber da müssen Spezialisten ran.

Könntest Du das "Gelost" im Threadtitel löschen?

[frank]

das letzte problem mit rc_dot_svg hast du doch bestens gelöst. das prinzip hat sich also bewähert.
gab es nun nicht wieder entsprechende logmeldungen über irgendwas?

[the ratman]

ja, nur das rc-dot wars wohl nicht wirklich, muß damals zufall gewesen sein, ich hatte gestern alle 3 rg's, die die gfx verwenden brutal gelöscht und fhem neu gestartet - war dem ding egal. ich nehm mal an, damals hatte ich die starseite von fhem offen und da stehen genau die 3 rg's, damit ich auf einen blick batteriestände und gerätezustände sehen kann.
im fhem log gibts überhaupt nix. das tut so, als ob alles bestens wäre.
was beim strace auffällt: das schaut im "normalbetrieb" nicht viel anders aus, als wenns spinnt. allerdings rennen die infos da eher schubweise durch, wenns spinnt scheinen das wesentlich mehr meldungen als normal zu sein und alles rennt in affenartiger geschwindigkeit, wie wenn fhem keine rückmeldungen kriegen würd und deswegen immer permanent die devices abfragt. das nur als beschreibung, falls das verhalten wer kennt.
was halt lustig ist - nach ner zeit hört der spuk scheinbar von selber auf. die ersten beiden male nach 2 oder 3 stunden, diesmal halt beim neu anstarten in der früh. dumm is halt, dass ich keine zeit sagen kann, wanns passier ...

beim nächsten mal kopier ich mal meine config weg und nehm die demo.cfg. dann weiß ich wenigstens, obs an mir oder was anderem liegt.

[Wernieman]

...  hast DU eigentlich eine RTC?

[the ratman]

meinste ne echtzeituhr?

du sagst da was ... ich hatte mal kurzfristig eine s.usv. hab ich dann aber zurückgegeben. ich glaub die hatte ne echtzeituhr.
kanns sein?

aber wenn - wie kann ichs richten?
und wenn - warum spinnt das blöde ding ned immer beim rebooten oder so?

[Wernieman]

Die Frage ist, ob Du JETZT eine hast, die hätte ich mal testweise entfernt ...

Bekannt ist, das FHEM Probleme hat, wenn Die Uhrzeit spinnt .... bzw. DU könntest, wenn DU Probleme hast, gucken was die Uhrzeit macht ...

[the ratman]

ein raspi hat von haus aus keine, meines wissens nach.
somit hat mein raspi auch keine drinnen.
wie gesagt: die s.usv hatte eine - und ich kann mich nicht erinnern, irgendwelche sachen nach dem entfernen der s.usv umgestellt zu haben. drum auch meine vorherige vermutung, dass ich da noch altlasten haben könnte.

ich weiß jetzt nur, dass auch div. zeitabfragen beim "spinnen" aufgetaucht sind
hatte ich hier schon nebenher gepostet - ich wiederhole (ist zwar vom vorletzten mal, aber gestern auch wieder gekommen):

Code Auswählen Erweitern

brk(0x56ab000)                          = 0x56ab000
brk(0x564c000)                          = 0x564c000
read(9, "E461363,0000,0003BB0C,FF,FFCD,4B"..., 4096) = 58
gettimeofday({1497185015, 975057}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 975381}, NULL) = 0
gettimeofday({1497185015, 975583}, NULL) = 0
gettimeofday({1497185015, 976009}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 976467}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 976702}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 977361}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 979764}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 980654}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 981715}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 982036}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 983188}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 983959}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 984742}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 985123}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 986911}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 987352}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 990521}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 991217}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 991739}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 992117}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 995018}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 995683}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 996209}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 996560}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 998325}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185015, 998775}, NULL) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185016, 497}, NULL)   = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185016, 949}, NULL)   = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185016, 3099}, NULL)  = 0
gettimeofday({1497185016, 3515}, NULL)  = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185016, 3781}, NULL)  = 0
gettimeofday({1497185016, 4414}, NULL)  = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2211, ...}) = 0
gettimeofday({1497185016, 4902}, NULL)  = 0
sonst kann ich dazu nur sagen, dass die uhrzeit zumindest auf der grafischen oberfläche gepasst hat, als er gestern gesponnen hat.

[Wernieman]

"grafischen oberfläche" ... liebe ich auf einem Server ....  Sorry für die Ironie

Wir reden hier von Millisekunden, das siehst Du grafisch nicht.

Code Auswählen Erweitern

date "+%H:%M:%S-%N"
Allerdings kann man manuell es auch schlecht sehen ;o)

Was mir noch einfäööt, hast Du einen Zeitabgleich per ntpdate ins Internet? Also nicht ntpd sondern per cron?

[the ratman]

ich hab zumindest keinen cronjob dafür angelegt. alles standard raspi quasi.

und ja, ich weiß, dass grafische oberflächen nicht die freunde von admins sind.
allerdings ist das meine einzige möglichkeit, tatsächlich ein paar infos zusammen zu tragen. mir nützt ja keine console was, wenn ich ned weiß, was ich reintippen soll. bei mir is die console nur für copy&paste da, damit helfende wie du infos kriegen.

brauchtest du das?

Code Auswählen Erweitern

pi@ratOhaus:~ $ date "+%H:%M:%S-%N"
16:20:41-998440212


[Wernieman]

Naja .. Du solltest bei Problemen damit gucken, was die Uhrzeit so sagt.

Nur um sicherzugehen:

Code Auswählen Erweitern

grep -r ntp /etc/cron* /var/spool/cron
ps aux | grep ntp

[the ratman]

ah, ok

deine 2 neuen mit sudo, weil er sonst wegen rechten jammert:

Code Auswählen Erweitern

pi@ratOhaus:~ $ sudo grep -r ntp /etc/cron* /var/spool/cron
/etc/cron.daily/ntp:# The default Debian ntp.conf enables logging of various statistics to
/etc/cron.daily/ntp:# the /var/log/ntpstats directory.  The daemon automatically changes
/etc/cron.daily/ntp:statsdir=$(cat /etc/ntp.conf | grep -v '^#' | sed -n 's/statsdir \([^ ][^ ]*\)/\1/p')
pi@ratOhaus:~ $ sudo ps aux | grep ntp
ntp        881  0.0  0.4   5776  3824 ?        Ss   Jun14   0:06 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /var/lib/ntp/ntp.conf.dhcp -u 106:111
pi       18775  0.0  0.2   4300  2016 pts/1    S+   16:36   0:00 grep --color=auto ntp
pi@ratOhaus:~ $



[Wernieman]

1. Du hast keinen Timecron
2. Du hast einen echten ntpd laufen

Um es einfach Mal auszuprobieren, könntest Du den ntpd runterfahren und Testen?

[the ratman]

mein taskmanager lässt mich nicht und console - wie immer - weiß ich ned wie.

btw - wenn du mir sagst, wie ich den abstell, dann bitte auch gleich sagen, wie der wieder an geht - so spielchen wie gestern kosten mir immer 5 min meines lebens. das brauch ich ned jeden tag, sonst hast du bald keinen mehr, der dir auf die nerven gehen kann *g*.
btw - erst mal ein fettes danke für deine zeit, gelle!

[Wernieman]

Sorry aber gewisse "basics" sollst Du Dir aneignen. Außerdem kann (und will) ich nur Hilfe zur Selbsthilfe geben.

Ohne getestet zu haben:

Code Auswählen Erweitern

sudo /etc/init.d/ntpd stop

P.S. bash kennt die "tape-completion". drucke mal nach "/etc/init.d/n" 2 mal TAB ...

[the ratman]

Code Auswählen Erweitern

root@luke7:~# sudo /etc/init.d/ntpd stop
sudo: /etc/init.d/ntpd: Kommando nicht gefunden

ich finde nur einen "ntp" ohne "d"

mache ich
root@luke7:~# sudo /etc/init.d/ntp stop
kommt zumindest 5 min. lang mal nix besonderes. perl tuckert weiterhin mit 1% oder weniger rum. hab dann das ding wieder mit start angeworfen.

[Wernieman]

Neee ... Du solstest es mal 3-4 Tage OHNE ntp ausprobieren und gucken, ob Dein Problem damit zusammenhängt.

ntp ist eigentlich Harmlos, er gleicht nur die Uhrzeit mit Internetquellen ab. Wenn ein Zeitunterschied besteht, läst er die locale Uhr schneller/langsamer laufen, bis die zeiten sich angglichen haben. Dabei wird auch die Laufzeit der Signale Quelle-Empfänger berücksichtigt.

Zu Deinem Problem das es ntp und nicht ntpd lautet möchte ich mich Zitieren:

Ohne getestet zu haben:

[the ratman]

ok, dann schalten ma mal ab *g*

[the ratman]

hat auch nix geändert.
natürlich passiert nix, seitdem ich die sache genauer im auge hab *g*. mußte ja so kommen ...

aber ich hab da ne andere idee: ich hab mir mal alte werte mit dem raspi 2 angschaut (derzeit rennt fhem ja auf nem raspi 3).
und siehe da: die selben peaks, nur immer um rund 10 grad kälter.
könnte es sein, das der raspi 3 die temperaturen etwas anders angibt als der 2er und mir deshalb früher nie was aufgfallen ist, weil einfach noch keine warnung gekommen ist?
und wenn das so wäre, welche temperaturen treffen dann zu - die vom 2er oder die vom 3er?

[the ratman]

jetzt hab ihn mal erwischt.
die gewünschte "apptime"

Code Auswählen Erweitern

name                                     function                               max  count    total  average maxDly TS Max call     param Max call
WEB_192.168.178.35_39408                 FW_Read                                587     39    10301   264.13      0 24.06. 14:37:39 HASH(WEB_192.168.178.35_39408)
WEB_192.168.178.35_39406                 FW_Read                                535     50    11779   235.58      0 24.06. 14:37:35 HASH(WEB_192.168.178.35_39406)
WEB_192.168.178.35_39278                 FW_Read                                533     46    11232   244.17      0 24.06. 14:37:04 HASH(WEB_192.168.178.35_39278)
WEB_192.168.178.35_39405                 FW_Read                                530     44     9245   210.11      0 24.06. 14:37:08 HASH(WEB_192.168.178.35_39405)
WEB_192.168.178.35_39484                 FW_Read                                525     38    14801   389.50      0 24.06. 14:36:58 HASH(WEB_192.168.178.35_39484)
tmr-LGTV_WebOS_TimerStatusRequest        HASH(0x35c5720)                        149      5      704   140.80   1407 24.06. 14:37:34 HASH(Fembotter)
tmr-at_Exec                              HASH(0x2befea0)                        112      1      112   112.00   1124 24.06. 14:36:57 HASH(AbfallAktuell)
ViewAbfall                               CALVIEW_Set                            100      1      100   100.00      0 24.06. 14:36:57 HASH(ViewAbfall); ViewAbfall; update
zWaveDongle                              ZWDongle_Read                           85      3      191    63.67      0 24.06. 14:37:13 HASH(zWaveDongle)
HMLan                                    HMLAN_Read                              62      5      142    28.40      0 24.06. 14:37:04 HASH(HMLan)
tmr-MPD_watch_idle                       HASH(0x38b2190)                         42      1       42    42.00   1580 24.06. 14:36:57 HASH(Luke7_Stream)
moRat_WetterEchtzeit_Handy               readingsGroup_Notify                    35     70      170     2.43      0 24.06. 14:37:04 HASH(moRat_WetterEchtzeit_Handy); HASH(SR_Terrasse)
tmr-at_Exec                              HASH(0x1037300)                         32      1       32    32.00    931 24.06. 14:36:53 HASH(heartbeat)
tmr-SYSMON_Update                        HASH(0x1036a78)                         31      1       31    31.00   1148 24.06. 14:37:39 HASH(ratOhaus_SM)
telnetForBlockingFn_1498303633_127.0.0.1_54090 telnet_Read                             27      1       27    27.00      0 24.06. 14:37:41 HASH(telnetForBlockingFn_1498303633_127.0.0.1_54090)
tmr-DbLog_execmemcache                   HASH(0xd0d5f8)                          20      2       36    18.00    744 24.06. 14:37:39 HASH(logdb)
tmr-PRESENCE_StartLocalScan              HASH(0x3a831c8)                         20      1       20    20.00    905 24.06. 14:37:34 HASH(Ping_Laserdrucker)
tmr-PRESENCE_StartLocalScan              HASH(0x28fec38)                         17      1       17    17.00    906 24.06. 14:37:34 HASH(Ping_FatBastard)
tmr-Twilight_sunpos                      HASH(0x3e1d4f0)                         17      1       17    17.00     83 24.06. 14:37:06 HASH(Twilight_sunpos)
tmr-DLNARenderer_renewSubscriptions      HASH(0x38cd190)                         16      1       16    16.00   1449 24.06. 14:37:10 HASH(DLNA_5C2BFF310001)
moRat_Lichter                            readingsGroup_Notify                    15     70       15     0.21      0 24.06. 14:36:57 HASH(moRat_Lichter); HASH(Terrasse_Wandlampe)

vielleicht hilfts ja: es fällt mir auf, dass ich z.b. keinerlei (subjektive) verzögerungen bei ansage über amad habe, oder wenn irgendwas an hardware-devices schaltet, lediglich das aufrufen der website dauert ewigkeiten.

[the ratman]

hab mich n bissi gespielt

ein "sudo killall perl" mit anschließenden starten von fhem wirkt ware wunder.

sollts das sein (ich glaub ja schon fast nimma dran), kann mir bitte jemand erklären, warum nicht mal ein reboot und/oder ausstecken des raspi hilft?
und - es darf auch gerne geraten sein - woran könnt sich perl so derartig aufhängen? ich nehm da auch gerne schlimmste vermutungen. vielleicht stößt mich ja eine davon dann in die richtige richtung ...

[Wernieman]

Bist Du Dir wirklich sicher, das es das perl vom FHEM ist?

Code Auswählen Erweitern

sudo killall perl
Killt nicht nur FHEM ....

[the ratman]

das is ja nur ne nothilfe
in nem anderen thread haben wir wohl den übeltäter schon gefunden: dlna-renderer in verbindung mit lg-tv modul mögen sich scheints nicht
ich geh mal davon aus, dass es der dlna-renderer is, weil der sowieso permanent warnings wirft.

also: hier https://forum.fhem.de/index.php/topic,73539.msg652443.html#msg652443 gehts weiter
war ungewollt, hat sich aber so entwickelt.