System wird langsam

stobor · 05 Mai 2019, 09:31:41

Hallo, vor ein paar Tagen habe ich ein FHEM UPDATE durchgeführt. Seitdem wird das System nach einiger Zeit immer langsamer. Wenn ich bspw einem Arduino Anschluss sage , dass er für 0,5sec einschalten soll, dauert es irgendwann dann 3sec. Zeitgesteuerte Events bleiben auch hängen. An meiner FHEM.cfg hat sich nichts geändert. Ein Neustart des PCs löst das Problem mal wieder für ein paar Minuten oder Stunden. Das war vor den Update alles kein Problem.

CoolTux · 05 Mai 2019, 09:33:59

Eventuell hat sich seit Deinem System welches Du vor 3 Jahren installiert und nie uogedatet hast und dem aktuellen Update von vor ein paar Tagen etwas eingeschlichen.

stobor · 05 Mai 2019, 09:53:23

Wie kann ich denn das Problem herausfinden?
Das letzte Update ist auch keine 3 Jahre her. Ich habe immer mal (unregelmäßig) Updates durchgeführt.

CoolTux · 05 Mai 2019, 10:02:53

Zitat von: stobor am 05 Mai 2019, 09:53:23
Wie kann ich denn das Problem herausfinden?
Das letzte Update ist auch keine 3 Jahre her. Ich habe immer mal (unregelmäßig) Updates durchgeführt.

Mit den Angaben nicht. Ich wollte Dich zum nachdenken anregen. Es fehlen einfach genaue Daten.
Was war das letzte Update wo Du das Problem noch nicht hattest. Welche Version von FHEM hast Du jetzt genau? Hast Du heute schon ein Update gemacht und mal geschaut ob es wieder ok ist?
Interessant ist von welchen Update zu welchen das Problem aufgetaucht sein könnte.

Was sagt das Logfile.

stobor · 05 Mai 2019, 10:14:29

Ich habe gerade noch mal ein Update durchgeführt:

Code Auswählen

2019.05.05 09:25:12 1: fhem
2019.05.05 09:25:12 1: RMDIR: ./restoreDir/update/2018-12-30
2019.05.05 09:25:12 1: UPD ./CHANGED
2019.05.05 09:25:12 1: UPD ./MAINTAINER.txt
2019.05.05 09:25:12 1: UPD ./fhem.cfg.demo
2019.05.05 09:25:12 1: UPD ./fhem.pl
2019.05.05 09:25:12 1: UPD FHEM/00_MYSENSORS.pm
2019.05.05 09:25:12 1: UPD FHEM/10_EnOcean.pm
2019.05.05 09:25:12 1: UPD FHEM/10_MYSENSORS_DEVICE.pm
2019.05.05 09:25:12 1: UPD FHEM/10_RESIDENTS.pm
2019.05.05 09:25:12 1: UPD FHEM/20_GUEST.pm
2019.05.05 09:25:12 1: UPD FHEM/20_PET.pm
2019.05.05 09:25:12 1: UPD FHEM/20_ROOMMATE.pm
2019.05.05 09:25:12 1: UPD FHEM/46_TRX_LIGHT.pm
2019.05.05 09:25:12 1: UPD FHEM/49_SSCam.pm
2019.05.05 09:25:12 1: UPD FHEM/50_MOBILEALERTSGW.pm
2019.05.05 09:25:12 1: UPD FHEM/73_AutoShuttersControl.pm
2019.05.05 09:25:12 1: UPD FHEM/73_GardenaSmartBridge.pm
2019.05.05 09:25:12 1: UPD FHEM/76_SMAInverter.pm
2019.05.05 09:25:12 1: UPD FHEM/93_DbRep.pm
2019.05.05 09:25:12 1: UPD FHEM/98_DOIF.pm
2019.05.05 09:25:13 1: UPD FHEM/98_Installer.pm
2019.05.05 09:25:13 1: UPD FHEM/98_Modbus.pm
2019.05.05 09:25:13 1: UPD FHEM/98_RandomTimer.pm
2019.05.05 09:25:13 1: UPD FHEM/98_Text2Speech.pm
2019.05.05 09:25:13 1: UPD FHEM/98_WeekdayTimer.pm
2019.05.05 09:25:13 1: UPD FHEM/98_autocreate.pm
2019.05.05 09:25:13 1: UPD FHEM/98_vitoconnect.pm
2019.05.05 09:25:13 1: UPD FHEM/98_weekprofile.pm
2019.05.05 09:25:13 1: UPD FHEM/RESIDENTStk.pm
2019.05.05 09:25:13 1: UPD FHEM/holiday/ni.holiday
2019.05.05 09:25:13 1: UPD FHEM/holiday/ns.holiday
2019.05.05 09:25:13 1: UPD FHEM/holiday/th.holiday
2019.05.05 09:25:13 1: UPD FHEM/lib/AttrTemplate/httpmod.template
2019.05.05 09:25:13 1: UPD FHEM/lib/AttrTemplate/mqtt2.template
2019.05.05 09:25:13 1: UPD FHEM/lib/fhem_zwave_deviceconfig.xml.gz
2019.05.05 09:25:13 1: UPD demolog/fhem.save
2019.05.05 09:25:13 1: UPD docs/commandref_frame.html
2019.05.05 09:25:13 1: UPD docs/commandref_frame_DE.html
2019.05.05 09:25:13 1: UPD www/gplot/ElsnerWS.gplot
2019.05.05 09:25:13 1: UPD www/gplot/ElsnerWS_2.gplot
2019.05.05 09:25:13 1: UPD www/gplot/ElsnerWS_3.gplot
2019.05.05 09:25:13 1: UPD www/pgm2/f18.js
2019.05.05 09:25:13 1: UPD www/pgm2/fhemweb.js
2019.05.05 09:25:13 1: saving fhem.cfg
2019.05.05 09:25:13 1: saving ./log/fhem.save
2019.05.05 09:25:13 1: 
2019.05.05 09:25:13 1: New entries in the CHANGED file:
2019.05.05 09:25:13 1:  - feature: 10_RESIDENTS: add home alone mode
2019.05.05 09:25:13 1:  - new:     20_PET: new RESIDENTS module type for pets at home
2019.05.05 09:25:13 1:  - bugfix:  73_AutoShuttersControl: fix bugs and logic problems
2019.05.05 09:25:13 1:  - feature: 98_weekprofile: HMCCU support
2019.05.05 09:25:13 1:  - change:  10_MYSENSORS_DEVICE: enhance support for SetExtensions;
2019.05.05 09:25:13 1:                       separate readings for heatrbeat, smartSleep & NACK
2019.05.05 09:25:13 1:  - bugfix:  73_GardenaSmartBridge: fix undefined_value Error
2019.05.05 09:25:13 1:  - feature: 98_Text2Speech: add Amazon Polly as new suggested TTS-Engine
2019.05.05 09:25:13 1:               due best quality 
2019.05.05 09:25:13 1:  - bugfix:  73_AutoShuttersControl: fix shading absent and coming home, fix
2019.05.05 09:25:13 1:               Reading ASC_Time_PrivacyDriveDown, fix blocking shutter then
2019.05.05 09:25:13 1:               shading drive and terrace door open
2019.05.05 09:25:13 1:  - change:  Newly introduced bank holiday for Thuringia: Weltkindertag (20.09.)
2019.05.05 09:25:13 1:  - bugfix:  74_AutoShuttersControl: fix window closed after sunset and ModeUp
2019.05.05 09:25:13 1:                                      absent, fix other bugs
2019.05.05 09:25:13 1:  - bugfix:  76_SMAInverter: perl warnings,Forum:#56080.msg933276.html#msg933276
2019.05.05 09:25:13 1:  - feature: 73_AutoShuttersControl: add attribut ASC_RainProtection, bugfix
2019.05.05 09:25:13 1:  - feature: 73_AutoShuttersControl: add attribut ASC_WindProtection
2019.05.05 09:25:13 1:  - bugfix:  73_AutoShuttersControl: fix shutters drive after partyMode off and
2019.05.05 09:25:13 1:                shutter have partyMode off attribut
2019.05.05 09:25:13 1:  - feature: 93_DbRep: FHEM command "dbReadingsVal" implemented
2019.05.05 09:25:13 1:  - change:  49_SSCam: Meta.json and minor code change
2019.05.05 09:25:13 1:  - change:  50_MOBILEALERTSGW: Checksum check added
2019.05.05 09:25:13 1:  - change:  93_DbRep: check index "Report_Idx" during first DB connect
2019.05.05 09:25:13 1:  - change:  98_RandomTimer: remove 59_Twilight dependency
2019.05.05 09:25:13 1:  - feature: 93_DbRep: new set "index" command to manage needed indexe for 
2019.05.05 09:25:13 1: ... rest of lines skipped.
2019.05.05 09:25:13 1: 
2019.05.05 09:25:13 1: 
2019.05.05 09:25:13 1: fhemtabletui
2019.05.05 09:25:14 1: nothing to do...
2019.05.05 09:25:14 1: Calling /usr/bin/perl ./contrib/commandref_join.pl -noWarnings, this may take a while
2019.05.05 09:25:55 1: 
2019.05.05 09:25:55 1: update finished, "shutdown restart" is needed to activate the changes.

Wie kann ich denn herausfinden, wann das letzte Update war?

Im Log war nichts Auffälliges. Ich habe gerade noch einmal das Loglevel erhöht (von 2):
attr global verbose 4

CoolTux · 05 Mai 2019, 10:18:38

Gib mal
version
in der FHEM Kommandozeile ein.

Otto123 · 05 Mai 2019, 10:19:00

Zitat von: stobor am 05 Mai 2019, 10:14:29
Wie kann ich denn herausfinden, wann das letzte Update war?

Moin,

Code Auswählen

restore list update

Gruß Otto

stobor · 05 Mai 2019, 10:20:07

restore list update:
Available for restore in update:
2019-01-07
2019-04-26
2019-05-05

Nach dem 7. Januar 2019 war noch alles super.

version liefert:
Latest Revision: 19330
File Rev Last Change
fhem.pl 19328 2019-05-04 19:13:22Z rudolfkoenig
90_at.pm 17561 2018-10-18 14:45:30Z rudolfkoenig
00_CUL.pm 17559 2018-10-18 07:45:07Z rudolfkoenig
10_CUL_HM.pm 19225 2019-04-20 06:53:45Z martinp876
98_dummy.pm 19197 2019-04-16 05:38:59Z rudolfkoenig
91_eventTypes.pm 14888 2017-08-13 12:07:12Z rudolfkoenig
01_FHEMWEB.pm 19148 2019-04-08 12:24:10Z rudolfkoenig
92_FileLog.pm 19102 2019-04-02 19:48:57Z rudolfkoenig
95_FLOORPLAN.pm 13735 2017-03-19 12:43:53Z UliM
10_FRM.pm 15941 2018-01-20 21:20:20Z jensb
20_FRM_IN.pm 18939 2019-03-17 10:22:23Z jensb
20_FRM_OUT.pm 15928 2018-01-19 21:07:42Z jensb
10_FS20.pm 14888 2017-08-13 12:07:12Z rudolfkoenig
12_HMS.pm 16797 2018-05-29 19:35:43Z rudolfkoenig
02_HTTPSRV.pm 16874 2018-06-15 17:18:55Z neubert
No Id found for 99_myUtils.pm
91_notify.pm 17225 2018-08-29 12:34:29Z rudolfkoenig
98_RandomTimer.pm 19279 2019-04-28 17:10:32Z Beta-User
98_restore.pm 16426 2018-03-17 16:23:45Z rudolfkoenig
49_SSCam.pm 19280 2019-04-28 17:20:21Z DS_Starter
99_SUNRISE_EL.pm 18732 2019-02-25 13:15:34Z rudolfkoenig
98_SVG.pm 18777 2019-03-03 13:16:05Z rudolfkoenig
99_Utils.pm 18920 2019-03-16 09:58:52Z rudolfkoenig
98_version.pm 15140 2017-09-26 09:20:09Z markusbloch
AttrTemplate.pm 19085 2019-04-01 17:00:24Z rudolfkoenig
No Id found for Base.pm
Blocking.pm 17553 2018-10-17 15:56:35Z rudolfkoenig

CoolTux · 05 Mai 2019, 10:41:50

Was ist Base.pm
Kannst Du uns den Inhalt einmal geben.

Nur zur Erklärung. Wir sammeln immer noch Infos zu Deinem System.

Otto123 · 05 Mai 2019, 12:09:20

Dann könntest Du mit einem der beiden Befehle auf den Stand vor dem (Update) Datum gehen:

Code Auswählen

restore update/2019-01-07
restore update/2019-04-26

Falls Cooltux nichts findet und Du die Geduld verlierst.

stobor · 05 Mai 2019, 12:46:06

Ich suche gerade nach der Base.pm, aber im Verzeichnis fhem/FHEM finde ich die nicht.
Sie liegt hier: FHEM\lib\Device\Firmata

CoolTux · 05 Mai 2019, 13:16:25

Ist schwierig was zu finden. Wie schaut es denn mit der Prozessauslastung aus wenn die Verzögerungen auf treten. Ich hatte das auch mal und dann war mein Perlprozess meist bei 90 oder gar 100 Prozent.
Also wenn das verzögert wieder an fängt schau mal mit top nach wie die Auslastung ist.

stobor · 05 Mai 2019, 14:15:19

Wo/ wie soll ich denn top eingeben?

CoolTux · 05 Mai 2019, 14:16:07

In Deiner Linux Shell

stobor · 05 Mai 2019, 19:53:10

Aktuell läuft noch alles, aber ich habe top mal eingegeben:

Code Auswählen

top - 19:48:26 up 10:40,  1 user,  load average: 0,02, 0,03, 0,05
Tasks:  87 gesamt,   1 laufend,  86 schlafend,   0 gestoppt,   0 Zombie
%CPU(s):  1,3 be,  3,8 sy,  0,0 ni, 94,8 un,  0,0 wa,  0,0 hi,  0,0 si,  0,0 st
KiB Mem:   3934368 total,   763788 used,  3170580 free,    53116 buffers
KiB Swap:  4079612 total,        0 used,  4079612 free.   488140 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU %MEM     ZEIT+ BEFEHL
  515 syslog    20   0  255840   1272    808 S   1,0  0,0   1:02.46 rsyslogd
  985 mail      20   0   12688   1236   1028 S   0,7  0,0   0:51.07 nullmailer-send
 8365 jay       20   0   26544   1608   1188 R   0,7  0,0   0:00.81 top
    7 root      20   0       0      0      0 S   0,3  0,0   0:14.06 rcu_sched
   28 root      20   0       0      0      0 S   0,3  0,0   0:10.32 kworker/0:1
 8389 mail      20   0   39364   1800   1436 S   0,3  0,0   0:00.01 smtp
    1 root      20   0   33488   2752   1448 S   0,0  0,1   0:01.90 init
    2 root      20   0       0      0      0 S   0,0  0,0   0:00.00 kthreadd
    3 root      20   0       0      0      0 S   0,0  0,0   0:00.52 ksoftirqd/0
    4 root      20   0       0      0      0 S   0,0  0,0   0:00.00 kworker/0:0
    5 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 kworker/0:0H
    6 root      20   0       0      0      0 S   0,0  0,0   0:00.00 kworker/u4:0
    8 root      20   0       0      0      0 S   0,0  0,0   0:08.87 rcuos/0
    9 root      20   0       0      0      0 S   0,0  0,0   0:07.11 rcuos/1
   10 root      20   0       0      0      0 S   0,0  0,0   0:00.00 rcu_bh
   11 root      20   0       0      0      0 S   0,0  0,0   0:00.00 rcuob/0
   12 root      20   0       0      0      0 S   0,0  0,0   0:00.00 rcuob/1
   13 root      rt   0       0      0      0 S   0,0  0,0   0:01.03 migration/0
   14 root      rt   0       0      0      0 S   0,0  0,0   0:00.29 watchdog/0
   15 root      rt   0       0      0      0 S   0,0  0,0   0:00.28 watchdog/1
   16 root      rt   0       0      0      0 S   0,0  0,0   0:00.94 migration/1
   17 root      20   0       0      0      0 S   0,0  0,0   0:00.00 ksoftirqd/1
   19 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 kworker/1:0H
   20 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 khelper
   21 root      20   0       0      0      0 S   0,0  0,0   0:00.01 kdevtmpfs
   22 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 netns
   23 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 writeback
   24 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 kintegrityd
   25 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 bioset
   27 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 kblockd
   29 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 ata_sff
   30 root      20   0       0      0      0 S   0,0  0,0   0:00.14 khubd
   31 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 md
   32 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 devfreq_wq
   33 root      20   0       0      0      0 S   0,0  0,0   0:08.06 kworker/1:1
   35 root      20   0       0      0      0 S   0,0  0,0   0:00.01 khungtaskd
   36 root      20   0       0      0      0 S   0,0  0,0   0:00.00 kswapd0
   37 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 vmstat
   38 root      25   5       0      0      0 S   0,0  0,0   0:00.00 ksmd
   39 root      39  19       0      0      0 S   0,0  0,0   0:00.27 khugepaged
   40 root      20   0       0      0      0 S   0,0  0,0   0:00.00 fsnotify_mark
   41 root      20   0       0      0      0 S   0,0  0,0   0:00.00 ecryptfs-kthrea
   42 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 crypto
   54 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 kthrotld
   74 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 deferwq
   75 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 charger_manager
  126 root      20   0       0      0      0 S   0,0  0,0   0:00.01 scsi_eh_0

So gibt's etwas zum vergleichen. Sagt euch das schon etwas?

Das Loglevel reduziere ich mal wieder auf 3, oder ist 4 dauerhaft sinnvoll?