"hmlan1 new condition disconnected" seit FHEM update gestern

ujaudio · 06 Januar 2016, 10:45:07

Gestern mal wieder ein Update von FHEM gemacht, seitdem häufen sich Logeinträge, die ich vorher nicht hatte:

2016.01.06 00:30:54 1: 192.168.178.50:1000 disconnected, waiting to reappear (hmlan1)
2016.01.06 00:30:54 1: HMLAN_Parse: hmlan1 new condition disconnected
2016.01.06 00:30:55 1: 192.168.178.50:1000 reappeared (hmlan1)
2016.01.06 00:30:55 1: HMLAN_Parse: hmlan1 new condition init
2016.01.06 00:30:55 1: HMLAN_Parse: hmlan1 new condition ok
2016.01.06 00:56:30 1: 192.168.178.50:1000 disconnected, waiting to reappear (hmlan1)
2016.01.06 00:56:30 1: HMLAN_Parse: hmlan1 new condition disconnected
2016.01.06 00:56:31 1: 192.168.178.50:1000 reappeared (hmlan1)
2016.01.06 00:56:31 1: HMLAN_Parse: hmlan1 new condition init
2016.01.06 00:56:31 1: HMLAN_Parse: hmlan1 new condition ok
2016.01.06 02:00:48 1: 192.168.178.50:1000 disconnected, waiting to reappear (hmlan1)
2016.01.06 02:00:48 1: HMLAN_Parse: hmlan1 new condition disconnected
2016.01.06 02:00:49 1: 192.168.178.50:1000 reappeared (hmlan1)
2016.01.06 02:00:49 1: HMLAN_Parse: hmlan1 new condition init
2016.01.06 02:00:49 1: HMLAN_Parse: hmlan1 new condition ok
2016.01.06 02:17:48 1: 192.168.178.50:1000 disconnected, waiting to reappear (hmlan1)
2016.01.06 02:17:48 1: HMLAN_Parse: hmlan1 new condition disconnected
2016.01.06 02:17:49 1: 192.168.178.50:1000 reappeared (hmlan1)
2016.01.06 02:17:49 1: HMLAN_Parse: hmlan1 new condition init
2016.01.06 02:17:49 1: HMLAN_Parse: hmlan1 new condition ok

Muss mich das beunruhigen?

franky08 · 06 Januar 2016, 11:20:32

ZitatMuss mich das beunruhigen?

Ja!

HMLAN Firmware update auf 0.964, apptime starten und sehen ob irgendwas fhem "ausbremst", maxDly prüfen, Netzwerk prüfen

Sonst siehe: http://forum.fhem.de/index.php/topic,20776.0.html

ujaudio · 06 Januar 2016, 11:33:06

Dirmware ist aktuell,

aptime maxDly liefert:

Code Auswählen

                                name             function    max  count    total  average maxDly 
         tmr-PRESENCE_StartLocalScan      HASH(0x3cfcda0)     67     15      461    30.73   3792 HASH(jarhandy) 
 tmr-WifiLight_LowLevelCmdQueue_Send      HASH(0x3023c98)      7     14        7     0.50   3592 HASH(gb_wellness) 
 tmr-WifiLight_HighLevelCmdQueue_Exec      HASH(0x3023c98)      6     14        8     0.57   3542 HASH(gb_wellness) 
 tmr-WifiLight_LowLevelCmdQueue_Send      HASH(0x2e23060)      2     14        3     0.21   3500 HASH(ez_wellness) 
         tmr-FW_closeInactiveClients                          74     12      178    14.83   3488 
 tmr-WifiLight_HighLevelCmdQueue_Exec      HASH(0x2e23060)      1     14        1     0.07   3450 HASH(ez_wellness) 
          tmr-ROOMMATE_DurationTimer      HASH(0x4c2ccf0)    105      1      105   105.00   3393 HASH(rr_Juergen_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x481fff0)    100      1      100   100.00   3392 HASH(rr_Ursula_DurationTimer) 
                    tmr-BlockingKill      HASH(0x2612018)      3      1        3     3.00   3372 HASH(0x2612018) 
  tmr-SONOS_IsSubprocessAliveChecker      HASH(0x3036928)     72     24      733    30.54   3371 HASH(mm_sonos) 
                    tmr-BlockingKill      HASH(0x46d8360)      0      1        0     0.00   3367 
          tmr-ROOMMATE_DurationTimer      HASH(0x4474550)     98      1       98    98.00   3195 HASH(rr_Ursula_DurationTimer) 
                    tmr-BlockingKill      HASH(0x4c69010)      0      1        0     0.00   3021 
                    tmr-BlockingKill      HASH(0x48505b8)      0      1        0     0.00   3017 
          tmr-ROOMMATE_DurationTimer      HASH(0x4219418)    101      1      101   101.00   3017 HASH(rr_Ursula_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x4c2f680)    129      1      129   129.00   3017 HASH(rr_Juergen_DurationTimer) 
         tmr-PRESENCE_StartLocalScan      HASH(0x3e53390)     39     21      586    27.90   3014 HASH(TestTV) 
          tmr-ROOMMATE_DurationTimer      HASH(0x4807bf0)    107      1      107   107.00   3012 HASH(rr_Juergen_DurationTimer) 
                    tmr-BlockingKill      HASH(0x4475ae8)      0      1        0     0.00   3010 
          tmr-ROOMMATE_DurationTimer      HASH(0x3025450)    110      1      110   110.00   3010 HASH(rr_Ursula_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x40d10b0)    291      1      291   291.00   3010 HASH(rr_Juergen_DurationTimer)

Ich kann jetzt mal die Teile, die die großen Zahlen erzeugen auf diable stellen und schauen was sich tut. Was kann ich sonst noch herauslesen?

frank · 06 Januar 2016, 12:29:17

Zitataptime maxDly liefert:

nochmal mit apptime max.

ujaudio · 07 Januar 2016, 20:53:08

So, jetzt habe ich nochmals ein Update gemacht, dann ein restart und dann apptime maxDly:

Code Auswählen

                                name             function    max  count    total  average maxDly 
          tmr-ROOMMATE_DurationTimer      HASH(0x386c970)    106      1      106   106.00   3221 HASH(rr_Ursula_DurationTimer) 
  tmr-SONOS_IsSubprocessAliveChecker      HASH(0x27e3668)     71     11      300    27.27   3205 HASH(mm_sonos) 
         tmr-PRESENCE_StartLocalScan      HASH(0x35fe288)     40     10      367    36.70   3105 HASH(or_synology) 
          tmr-ROOMMATE_DurationTimer      HASH(0x3f986d0)    103      1      103   103.00   3019 HASH(rr_Ursula_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x383f358)     98      1       98    98.00   3010 HASH(rr_Juergen_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x385fd70)    107      1      107   107.00   3010 HASH(rr_Juergen_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x3b689c8)    281      1      281   281.00   3010 HASH(rr_Juergen_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x3e59d00)    107      1      107   107.00   3009 HASH(rr_Ursula_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x3f75ae0)    103      1      103   103.00   3009 HASH(rr_Juergen_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x3fb1838)     98      1       98    98.00   3009 HASH(rr_Juergen_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x3fb81a8)    100      1      100   100.00   3005 HASH(rr_Ursula_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x386c328)    105      1      105   105.00   2827 HASH(rr_Ursula_DurationTimer) 
                    tmr-harmony_ping      HASH(0x3712f48)      7      7       17     2.43   2070 HASH(or_harmony) 
                    tmr-BlockingKill      HASH(0x39a1e40)      0      1        0     0.00   1063 
                 tmr-HMLAN_KeepAlive     keepAlive:hmlan1     20     14       67     4.79   1053 keepAlive:hmlan1 
                 tmr-HMLAN_KeepAlive      keepAlive:hmusb      8     14       66     4.71    549 keepAlive:hmusb 
            tmr-HMLAN_KeepAliveCheck    keepAliveCk:hmusb      0     14        0     0.00    428 
 tmr-WifiLight_LowLevelCmdQueue_Send      HASH(0x27cfd78)      0      7        0     0.00    176 
                         tmr-at_Exec      HASH(0x3797de8)   1391      7     9556  1365.14    168 HASH(ez_moodlight) 
 tmr-WifiLight_HighLevelCmdQueue_Exec      HASH(0x27cfd78)      0      7        0     0.00    127 
 tmr-WifiLight_LowLevelCmdQueue_Send      HASH(0x25ce3b8)      0      7        0     0.00     85

apptime myx lieferrt gleich danach

Code Auswählen

                                name             function    max  count    total  average maxDly 
                              hmlan2          HMLAN_Ready   3004    448    18055    40.30      0 HASH(hmlan2) 
                         tmr-at_Exec      HASH(0x3797de8)   1391      8    10907  1363.38    168 HASH(ez_moodlight) 
                         ez_wellness        WifiLight_Set   1278      8    10014  1251.75      0 HASH(ez_wellness); ez_wellness; dim; 0; 30 
                        gb_followRGB          notify_Exec   1144      8     9044  1130.50      0 HASH(gb_followRGB); HASH(ez_wellness) 
                         gb_wellness        WifiLight_Set   1136      9     8982   998.00      0 HASH(gb_wellness); gb_wellness; RGB; 000000 
                              hmlan1           HMLAN_Read    583     34     5898   173.47      0 HASH(hmlan1) 
              WEB_178.10.6.104_34867              FW_Read    513     10      691    69.10      0 HASH(WEB_178.10.6.104_34867) 
                         tmr-at_Exec      HASH(0x276e890)    376      6     1406   234.33      7 HASH(Uhrzeitaktuell) 
                 tmr-Twilight_sunpos      HASH(0x3b45f08)    300      1      300   300.00     10 HASH(Daemmerung_sunpos) 
          tmr-ROOMMATE_DurationTimer      HASH(0x3b689c8)    281      1      281   281.00   3010 HASH(rr_Juergen_DurationTimer) 
                             Uhrzeit            dummy_Set    279     14      786    56.14      0 HASH(Uhrzeit); Uhrzeit; 21:17 
                               hmusb           HMLAN_Read    267     34     2249    66.15      0 HASH(hmusb) 
                             hz_ctrl          DOIF_Notify    249    125      960     7.68      0 HASH(hz_ctrl); HASH(pb_Raumsensor) 
                         tmr-at_Exec      HASH(0x32416f8)    191      2      372   186.00      6 HASH(reloadWeb) 
              WEB_178.10.6.104_34866              FW_Read    150      7      232    33.14      0 HASH(WEB_178.10.6.104_34866) 
                 tmr-Twilight_sunpos      HASH(0x3b975e0)    120      1      120   120.00      7 HASH(Daemmerung_sunpos) 
                    hz_hauptschalter            dummy_Set    111     11      417    37.91      0 HASH(hz_hauptschalter); hz_hauptschalter; off 
          tmr-ROOMMATE_DurationTimer      HASH(0x385fd70)    107      1      107   107.00   3010 HASH(rr_Juergen_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x3e59d00)    107      1      107   107.00   3009 HASH(rr_Ursula_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x386c970)    106      1      106   106.00   3221 HASH(rr_Ursula_DurationTimer) 
          tmr-ROOMMATE_DurationTimer      HASH(0x386c328)    105      1      105   105.00   2827 HASH(rr_Ursula_DurationTimer)

Im log ist folgender Eintrag zu finden:

Code Auswählen

2016.01.07 21:11:21 1: 192.168.178.50:1000 disconnected, waiting to reappear (hmlan1)
2016.01.07 21:11:21 1: HMLAN_Parse: hmlan1 new condition disconnected
2016.01.07 21:11:33 1: 192.168.178.50:1000 reappeared (hmlan1)
2016.01.07 21:11:33 1: HMLAN_Parse: hmlan1 new condition init
2016.01.07 21:11:39 1: HMLAN_Parse: hmlan1 new condition ok
2016.01.07 21:14:30 1: PERL WARNING: Subroutine HandleTimeout redefined at ./FHEM/98_apptime.pm line 24.
2016.01.07 21:14:30 1: PERL WARNING: Subroutine CallFn redefined at ./FHEM/98_apptime.pm line 58.
2016.01.07 21:16:04 1: PERL WARNING: Use of uninitialized value in join or string at ./FHEM/98_apptime.pm line 150.

martinp876 · 08 Januar 2016, 09:41:38

die 3 sec den HMLAN sind unschön aber kaum vermeidbar. Das ist das reconnect wenn der HMLAN nicht antwortet.

Zitattmr-at_Exec HASH(0x3797de8) 1391 8 10907 1363.38 168 HASH(ez_moodlight)
ez_wellness WifiLight_Set 1278 8 10014 1251.75 0 HASH(ez_wellness); ez_wellness; dim; 0; 30
gb_followRGB notify_Exec 1144 8 9044 1130.50 0 HASH(gb_followRGB); HASH(ez_wellness)
gb_wellness WifiLight_Set 1136 9 8982 998.00 0 HASH(gb_wellness); gb_wellness; RGB; 000000

das geht so nicht. die brauchen über 1 sec - durchschnittlich und blockierend. sie werden offensichtlich zyklisch aufgerufen. Das bringt zu 100% einen HMLAN disconnect.
Wer immer das geschrieben hat - lagere es aus (eigener Prozess). Das kann doch nur ein aktives warten sein.

FHEM Forum

"hmlan1 new condition disconnected" seit FHEM update gestern

ujaudio

franky08

ujaudio

frank

ujaudio

martinp876