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?
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
Sortier mal nach max und nicht maxDly. Außerdem kannst du mal global verbose 5 einstellen und freezemon installieren. Damit sieht man vielleicht mehr.
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
apptime nach max sortiert: 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)
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:
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.