HMLAN häufige Disconnects

[grappa24]

auch mich hats jetzt erwischt, anbei mal mein "apptime-log", seht Ihr da kritische Werte bzw. was kann ich zur Aufklärung loggen bzw. posten?

Code Auswählen Erweitern

name                                     function                               max    count      total  average   maxDly   avgDly TS Max call     param Max call
tmr-HMLAN_KeepAliveCheck                 keepAliveCk                              2      276     103.10     0.37 13437.74   253.40 27.03. 21:19:11 keepAliveCk:HMLAN1
tmr-CUL_HandleWriteQueue                 HASH(0x190b780)                          0       10       2.39     0.24 13000.38  7914.12 27.03. 21:30:30 HASH(CUL_1)
tmr-DENONX_AVR_GetStatus                 HASH(0x1f8f338)                        100      231    7911.22    34.25 12121.81   224.45 27.03. 20:56:53 HASH(denon)
tmr-FW_closeInactiveClients              0                                       24      116    1407.31    12.13 12072.30   377.41 27.03. 21:53:52 0
tmr-PRESENCE_StartLocalScan              HASH(0x1cd1ff0)                        130       75    3470.47    46.27 11625.05   173.11 27.03. 20:28:59 HASH(samsung)
tmr-HUEBridge_GetUpdate                  HASH(0x2db4940)                       1142      116   26742.84   230.54 11051.94   329.19 27.03. 21:50:53 HASH(HUE)
tmr-HMLAN_KeepAlive                      keepAlive                               20      277    1566.98     5.66 10814.44   239.67 27.03. 21:43:33 keepAlive:HMLAN1
tmr-CUL_HM_ActCheck                      ActionDetector                         633       12    3973.01   331.08 10521.00   894.78 27.03. 20:41:28 ActionDetector
tmr-DOIF_TimerTrigger                    REF(0x38a0998)                          20        1      20.38    20.38 10314.64 10314.64 27.03. 20:28:35 REF(0x38a0998)
tmr-DOIF_TimerTrigger                    REF(0x36f0520)                         194        1     194.46   194.46 10306.23 10306.23 27.03. 20:51:10 REF(0x36f0520)
tmr-BlockingKill                         HASH_unnamed                             0       41       8.84     0.22  8643.89   219.55 27.03. 21:09:16 HASH(0x37f49f8)
tmr-Twilight_sunpos                      HASH(0x37c56c0)                         10        1      10.62    10.62  7537.36  7537.36 27.03. 20:41:08 HASH(myTwilight_sunpos)
tmr-DOIF_TimerTrigger                    REF(0x38ad860)                         203        1     203.94   203.94  6290.06  6290.06 27.03. 21:50:06 REF(0x38ad860)
tmr-FBAHAHTTP_Poll                       HASH(0x3140a58)                         42       24     302.80    12.62  5844.70   251.01 27.03. 21:01:52 HASH(fbahahttp)
tmr-PRESENCE_StartLocalScan              HASH(0x1d4da10)                         55       38    1742.35    45.85  5816.14   296.22 27.03. 21:24:51 HASH(PC_Dieter)
tmr-PRESENCE_StartLocalScan              HASH(0x1d4d6f8)                        134       38    2159.52    56.83  5641.95   286.01 27.03. 21:50:06 HASH(PC_Heike)
tmr-FRITZBOX_Readout_Start               FritzBox.Readout                        54       23    1085.46    47.19  4139.03   185.92 27.03. 21:07:10 FritzBox.Readout


[mumpitzstuff]

Sortier mal nach max und nicht maxDly. Außerdem kannst du mal global verbose 5 einstellen und freezemon installieren. Damit sieht man vielleicht mehr.

[eisman]

hi,

ich melde mich auch mal im Club an,
seit 3 Tagen habe ich auch das Problem,
  1. Lan-Team abgeschaltet -> Verbesserung (vorher alle 30sek eine Meldung)
  2. ipV6 abgeschaltet -> Verbesserung (vorher alle 1min eine Meldung)
und jetzt nach 2.stunden wieder das Problem da.
Fhem läuft aber stabil und PRESENCE zeigt keine abbrüche
langzeit ping auf das Lan-Device keine Probleme.

vieleicht hilfs weiter...

gruss

[grappa24]

apptime nach max sortiert:

Code Auswählen Erweitern

name                                     function                               max    count      total  average   maxDly   avgDly TS Max call     param Max call
CUL_1                                    CUL_Read                             15358     1118  765837.30   685.01     0.00     0.00 28.03. 09:32:37 HASH(CUL_1)
fhem_server_2                            FHEM2FHEM_Read                       13244      823  653805.39   794.42     0.00     0.00 28.03. 14:44:42 HASH(fhem_server_2)
tmr-HUEBridge_GetUpdate                  HASH(0x2db4940)                       5394     1255  284198.89   226.45 11980.67   134.53 28.03. 07:49:30 HASH(HUE)
notifier_AgroWeatherDay                  notify_Exec                           4537       10   44562.30  4456.23     0.00     0.00 28.03. 15:51:40 HASH(notifier_AgroWeatherDay); HASH(AgroWetter)
WEBphone                                 FW_Read                               4022      340   63652.49   187.21     0.00     0.00 27.03. 20:51:22 HASH(WEBphone)
HMLAN1                                   HMLAN_Read                            2907     6351 1614512.65   254.21     0.00     0.00 27.03. 23:37:17 HASH(HMLAN1)
tmr-at_Exec                              HASH(0x3968718)                       2722        1    2722.65  2722.65     5.32     5.32 27.03. 20:01:06 HASH(myAt)
di_rollo_schlitz                         dummy_Set                             2714       12    2719.85   226.65     0.00     0.00 27.03. 20:01:06 HASH(di_rollo_schlitz); di_rollo_schlitz; off
notifier_di_rollo_schlitz                notify_Exec                           2628        1    2628.72  2628.72     0.00     0.00 27.03. 20:01:06 HASH(notifier_di_rollo_schlitz); HASH(di_rollo_schlitz)
WetterBox                                readingsGroup_Notify                  2604    25348   55169.82     2.18     0.00     0.00 28.03. 15:51:35 HASH(WetterBox); HASH(AgroWetter)
tmr-at_Exec                              HASH(0x3124dd0)                       2594        1    2594.64  2594.64     6.48     6.48 28.03. 03:00:02 HASH(myAtLadekontrolle)
wz_wbaum                                 FS20_Set                              2583       26    2994.63   115.18     0.00     0.00 28.03. 03:00:02 HASH(wz_wbaum); wz_wbaum; on-till; 06:00:00
WEB                                      FW_Read                               2056      180   29261.15   162.56     0.00     0.00 28.03. 12:36:47 HASH(WEB)
HEOS_Master                              HEOSMaster_Read                       2020      133   51515.84   387.34     0.00     0.00 28.03. 14:57:56 HASH(HEOS_Master)
wz_rollo_alle                            KNX_Set                               1888       19    2898.06   152.53     0.00     0.00 28.03. 07:08:36 HASH(wz_rollo_alle); wz_rollo_alle; on
tmr-DOIF_TimerTrigger                    REF(0x3833500)                        1586        1    1586.91  1586.91     6.49     6.49 27.03. 20:08:01 REF(0x3833500)
tmr-RandomTimer_Exec                     HASH(0x38c8f30)                       1581        1    1581.49  1581.49     8.39     8.39 27.03. 21:15:38 HASH(ZufallsTimerAnwSim_Exec)
tmr-at_Exec                              HASH(0x2f74618)                       1525       84   17781.46   211.68   346.00    11.26 27.03. 20:20:47 HASH(pageRefreshAt)
fp_time                                  dummy_Set                             1471     2565  124363.31    48.48     0.00     0.00 27.03. 20:08:01 HASH(fp_time); fp_time; 20:08
pageRefresh                              dummy_Set                             1440      186   10610.46    57.05     0.00     0.00 27.03. 20:20:47 HASH(pageRefresh); pageRefresh; toggle
tmr-RandomTimer_Exec                     HASH(0x37c0818)                       1410        1    1410.95  1410.95     3.67     3.67 27.03. 20:25:21 HASH(ZufallsTimerAnwSim_Exec)
tmr-DOIF_TimerTrigger                    REF(0x3726430)                        1389        1    1389.23  1389.23     4.48     4.48 27.03. 22:43:01 REF(0x3726430)
KNX                                      TUL_Read                              1374       72   14219.48   197.49     0.00     0.00 28.03. 07:08:39 HASH(KNX)
tmr-DOIF_TimerTrigger                    REF(0x42773b0)                        1345        1    1345.66  1345.66     4.91     4.91 28.03. 08:06:01 REF(0x42773b0)
tmr-DOIF_TimerTrigger                    REF(0x3f454b8)                        1340        1    1340.11  1340.11     6.68     6.68 28.03. 07:54:01 REF(0x3f454b8)
tmr-DOIF_TimerTrigger                    REF(0x404ef60)                        1317        1    1317.18  1317.18     7.23     7.23 28.03. 11:03:01 REF(0x404ef60)
notifier_FS20_wz_denon                   notify_Exec                           1303        2    2588.30  1294.15     0.00     0.00 28.03. 07:11:35 HASH(notifier_FS20_wz_denon); HASH(FS20_wz_denon)
denon                                    DENONX_AVR_Set                        1292       16    2595.24   162.20     0.00     0.00 28.03. 07:11:35 HASH(denon); denon; on
tmr-DOIF_TimerTrigger                    REF(0x46a7668)                        1240        1    1240.19  1240.19    10.62    10.62 28.03. 15:11:01 REF(0x46a7668)
tmr-CUL_HM_ActCheck                      ActionDetector                        1174      126   52342.66   415.42 10521.00   156.75 28.03. 16:22:37 ActionDetector
tmr-HUEDevice_GetUpdate                  HASH(0x2e81ed8)                       1131       13    3236.72   248.98     5.96     5.40 28.03. 07:08:56 HASH(HUEDevice1)
tmr-WakeUpFn                             HASH_unnamed                          1129        1    1129.37  1129.37     4.17     4.17 27.03. 20:01:10 HASH(0x38dce88)
tmr-DOIF_TimerTrigger                    REF(0x389cb18)                        1127        1    1127.42  1127.42     5.25     5.25 27.03. 23:29:01 REF(0x389cb18)
di_rollo_kurz                            KNX_Set                               1126       12    1165.05    97.09     0.00     0.00 27.03. 20:01:10 HASH(di_rollo_kurz); di_rollo_kurz; on
tmr-DOIF_TimerTrigger                    REF(0x4691d70)                        1126        1    1126.41  1126.41     6.19     6.19 28.03. 16:59:01 REF(0x4691d70)
FileLog_rt_bd_dg                         FileLog_Get                           1062        1    1062.84  1062.84     0.00     0.00 28.03. 07:07:01 HASH(FileLog_rt_bd_dg); FileLog_rt_bd_dg; CURRENT; INT; 2018-03-28_00:00:00; 2018-03-28_23:59:59; 4:rt_bd_dg.desired-temp\x3a::; 4:rt_bd_dg.measured-temp\x3a::; 4:rt_bd_dg.actuator\x3a::
tmr-DOIF_TimerTrigger                    REF(0x368b3a0)                        1053        1    1053.66  1053.66     5.30     5.30 28.03. 03:46:01 REF(0x368b3a0)
FileLog_rt_bd_og                         FileLog_Get                           1010        1    1010.72  1010.72     0.00     0.00 28.03. 07:07:02 HASH(FileLog_rt_bd_og); FileLog_rt_bd_og; CURRENT; INT; 2018-03-28_00:00:00; 2018-03-28_23:59:59; 4:rt_bd_og.desired-temp\x3a::; 4:rt_bd_og.measured-temp\x3a::; 4:rt_bd_og.actuator\x3a::
tmr-DOIF_TimerTrigger                    REF(0x41dac10)                         997        1     997.37   997.37  2602.17  2602.17 28.03. 03:00:03 REF(0x41dac10)
og_fenster                               structure_Notify                       756    25348   76480.07     3.02     0.00     0.00 28.03. 07:02:15 HASH(og_fenster); HASH(he_fenster)
dg_fenster                               structure_Notify                       715    25348   38484.67     1.52     0.00     0.00 28.03. 05:47:14 HASH(dg_fenster); HASH(dg_fenster_rechts)

[mumpitzstuff]

Ich bin jetzt auch kein Experte bei der Analyse solcher Daten, aber dein System macht einen ziemlich überlasteten Eindruck. Durch die Sortierung nach max hast du die Dinge oben stehen, die die größten Ausreißer produziert haben. maxDly gibt dir nur an, welche Dinge am meisten durch äußere Einflüsse verzögert wurden.

notifier_AgroWeatherDay 10x aufgerufen mit 4,5s Laufzeit
CUL_1 1118x aufgerufen und im Mittel 685ms Laufzeit
usw.
usw.

Viele Dinge die da einmalig aufgerufen werden schlagen auch gleich mit Laufzeiten größer 1s rein z.B. myAtLadekontrolle, myAt, notifier_di_rollo_schlitz usw.

Nur mal so zum Vergleich meine Daten:

Code Auswählen Erweitern

active-timers: 46; max-active timers: 51; max-timer-load: 7  min-tmrHandlingTm: 0.1ms; max-tmrHandlingTm: 822.1ms; totAvgDly: 5.4ms

name                                     function                               max    count      total  average   maxDly   avgDly TS Max call     param Max call
rg_Dienstplan                            readingsGroup_Notify                  8106    47867   22610.18     0.47     0.00     0.00 28.03. 00:15:20 HASH(rg_Dienstplan); HASH(DIENSTPLAN_VIEW)
rg_WETTER_AGRAR                          readingsGroup_Notify                  1583    47867   15715.07     0.33     0.00     0.00 28.03. 00:05:38 HASH(rg_WETTER_AGRAR); HASH(WETTER_AGRAR)
PLANT_STATUS                             readingsGroup_Notify                  1056    47867   15594.37     0.33     0.00     0.00 27.03. 19:15:13 HASH(PLANT_STATUS); HASH(DIENSTPLAN_VIEW)
WEB_194.39.218.10_1835                   FW_Read                                915        6     938.92   156.49     0.00     0.00 28.03. 18:22:59 HASH(WEB_194.39.218.10_1835)
tmr-WUup_stateRequestTimer               HASH(0x3938aa8)                        821      290   48072.63   165.77    46.73     2.20 27.03. 22:54:08 HASH(WETTER_UPLOAD)
tmr-at_Exec                              HASH(0x4d37968)                        735        1     735.56   735.56     1.98     1.98 28.03. 09:58:19 HASH(alarm6.arm.dly)
PUSHBULLET                               Pushbullet_Set                         683       15     686.85    45.79     0.00     0.00 28.03. 09:58:19 HASH(PUSHBULLET); PUSHBULLET; message; ARMED; |; ALARMANLAGE
tmr-Wunderground_GetStatus               HASH(0x38cfd98)                        503       96    6798.38    70.82   111.45    17.27 27.03. 21:59:53 HASH(WETTER2)
tmr-Wunderground_GetStatus               HASH(0x376eef0)                        453       96   14323.85   149.21   163.75    14.05 27.03. 22:14:53 HASH(WETTER)
rg_Abfall                                readingsGroup_Notify                   283    33865   14657.52     0.43     0.00     0.00 28.03. 02:15:09 HASH(rg_Abfall); HASH(ABFALL_LEERUNG)
GTAG_ANNE                                PRESENCE_Read                          258     2547   11268.62     4.42     0.00     0.00 28.03. 09:58:13 HASH(GTAG_ANNE)
tmr-Wunderground_GetStatus               HASH(0x38cfcc0)                        256       96    6564.37    68.38   129.61    17.01 27.03. 22:44:53 HASH(WETTER1)
HMLAN1                                   HMLAN_Read                             253     5831  105537.24    18.10     0.00     0.00 28.03. 07:29:44 HASH(HMLAN1)
rr_ANNE                                  RESIDENTStk_Notify                     240      331     478.20     1.44     0.00     0.00 28.03. 09:58:13 HASH(rr_ANNE); HASH(GTAG_ANNE)
tmr-at_Exec                              HASH(0x2dd5fd8)                        237        1     237.96   237.96     2.61     2.61 28.03. 00:00:00 HASH(SUNLIGHT_AVG)
rr_ANNE                                  RESIDENTStk_Set                        226       40     360.14     9.00     0.00     0.00 28.03. 09:58:13 HASH(rr_ANNE); rr_ANNE; absent
GROUP_FAMILIE                            RESIDENTStk_Notify                     202     4678    4970.09     1.06     0.00     0.00 28.03. 09:58:13 HASH(GROUP_FAMILIE); HASH(rr_ANNE)
alarm6.arm.N                             notify_Exec                            157     1452    1420.69     0.98     0.00     0.00 28.03. 09:58:13 HASH(alarm6.arm.N); HASH(GROUP_FAMILIE)
tmr-XiaomiFlowerSens_stateRequestTimer   HASH(0x379d4b8)                        133       23    1466.89    63.78     2.33     1.77 28.03. 12:01:16 HASH(CASTANOS)
tmr-at_Exec                              HASH(0x38d0278)                        126      725   41771.95    57.62   170.56     2.99 28.03. 14:18:46 HASH(DHT22_AT)
tmr-SIRD_Update                          HASH(0x3bad700)                        118     8690  226996.18    26.12  5956.89     6.02 27.03. 18:45:33 HASH(WLAN_RADIO)
GTAG_ACHIM                               PRESENCE_Read                          114     3230   14157.81     4.38     0.00     0.00 28.03. 08:25:39 HASH(GTAG_ACHIM)
WETTER_ANSAGEN_DI                        DOIF_Notify                             99    47867   14316.90     0.30     0.00     0.00 28.03. 07:29:44 HASH(WETTER_ANSAGEN_DI); HASH(MOTION_WOHNZIMMER)
rr_ACHIM                                 RESIDENTStk_Notify                      98      329     223.15     0.68     0.00     0.00 28.03. 08:25:39 HASH(rr_ACHIM); HASH(GTAG_ACHIM)
tmr-HttpUtils_Err                        HASH_unnamed                            98       18    1066.09    59.23   496.72    28.95 28.03. 18:14:57 HASH(0x5a543a8)
NANOCUL                                  CUL_Read                                96    13417  121688.19     9.07     0.00     0.00 27.03. 18:21:38 HASH(NANOCUL)
tmr-SYSMON_Update                        HASH(0x2246020)                         92     1449   62856.70    43.38  3417.55     7.10 27.03. 18:55:38 HASH(RASPBERRY)
tmr-HMinfo_autoUpdate                    sUpdt                                   85       24    1344.60    56.02     2.37     1.79 27.03. 21:14:40 sUpdt:HMINFO
tmr-XiaomiFlowerSens_stateRequestTimer   HASH(0x237eb90)                         85       23    1418.34    61.67     4.35     1.93 28.03. 11:55:22 HASH(PFLANZE_BODEN)
tmr-CUL_HM_motionCheck                   MOTION_WOHNZIMMER                       83       38    1737.88    45.73    13.99     2.25 28.03. 07:33:46 MOTION_WOHNZIMMER:motionCheck
rr_ACHIM                                 RESIDENTStk_Set                         80       40     120.41     3.01     0.00     0.00 28.03. 08:25:39 HASH(rr_ACHIM); rr_ACHIM; absent
tmr-TRAFFIC_StartUpdate                  HASH(0x2f5a490)                         78      145    8100.58    55.87   132.13    10.54 28.03. 17:44:54 HASH(TRAFFIC)
tmr-XiaomiFlowerSens_stateRequestTimer   HASH(0x37739e0)                         77       23    1375.90    59.82   140.28     8.47 28.03. 14:04:55 HASH(SCHNITTLAUCH)
tmr-XiaomiFlowerSens_stateRequestTimer   HASH(0x2486040)                         77       23    1426.40    62.02     2.15     1.54 27.03. 22:22:07 HASH(PFLANZE_FENSTER)
tmr-RESIDENTStk_DurationTimer            HASH(0x57f38b8)                         76        1      76.52    76.52     2.90     2.90 28.03. 14:32:40 HASH(rr_ACHIM_DurationTimer)
tmr-XiaomiFlowerSens_stateRequestTimer   HASH(0x3773f38)                         72       23    1350.85    58.73    52.32     4.02 28.03. 14:05:24 HASH(PETERSILIE)
AKKU_STATUS                              readingsGroup_Notify                    71    47867   15398.87     0.32     0.00     0.00 27.03. 18:21:38 HASH(AKKU_STATUS); HASH(RASPBERRY)
tmr-XiaomiFlowerSens_stateRequestTimer   HASH(0x3772298)                         70       22    1239.63    56.35     2.16     1.63 28.03. 09:59:25 HASH(BOGENHANF)
tmr-RESIDENTStk_DurationTimer            HASH(0x570f198)                         66        1      66.94    66.94     3.58     3.58 28.03. 14:31:40 HASH(rr_ACHIM_DurationTimer)
tmr-RESIDENTStk_DurationTimer            HASH(0x57dc3d0)                         66        1      66.34    66.34     3.19     3.19 28.03. 14:35:14 HASH(rr_ANNE_DurationTimer)
tmr-RESIDENTStk_DurationTimer            HASH(0x59a1f50)                         60        1      60.86    60.86     1.63     1.63 28.03. 18:20:42 HASH(rr_ACHIM_DurationTimer)

Meine average Werte sind durchweg viel kleiner als bei dir, was bedeutet, dass die Aufgaben schnell abgearbeitet werden. Manchmal gibts ein paar Ausreisser, aber die sind eher selten bei mir und dann als großer max Wert zu sehen.
Schade das es keine Spalte mit count * average gibt, nach der man sortieren könnte, dann würde man schnell die Hauptverursacher deiner Systemüberlastung sehen. So muss man sich das alles etwas zusammen suchen.

Vielleicht gibts aber auch noch irgendwo einen Profi, der mehr mit den Daten anfangen kann. Ich für meinen Teil würde mir die Dinge raus suchen die einen hohen average haben und einen hohen count und gucken ob sich da was optimieren lässt.

PS: Schau dir auch mal dein HMLAN und dein CUL genauer an, z.B. ob du siehst wieviel Nachrichten da rein kommen bzw. raus gehen. Vielleicht gibts bei dir an der Stelle auch zu viel Traffic.