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?
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
Dirmware ist aktuell,
aptime maxDly liefert:
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?
Zitataptime maxDly liefert:
nochmal mit apptime max.
So, jetzt habe ich nochmals ein Update gemacht, dann ein restart und dann apptime maxDly:
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
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:
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.
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.